-- Logs begin at Thu 2024-04-18 12:17:05 CEST, end at Thu 2024-04-18 13:31:50 CEST. -- Apr 18 13:30:00 volumio-salonw volumio[899]: info: Selecting previously unselected package gcc-8. Apr 18 13:30:00 volumio-salonw volumio[899]: info: Preparing to unpack .../15-gcc-8_8.3.0-6+rpi1_armhf.deb ... Apr 18 13:30:00 volumio-salonw volumio[899]: info: Unpacking gcc-8 (8.3.0-6+rpi1) ... Apr 18 13:30:01 volumio-salonw volumio[899]: info: Selecting previously unselected package gcc. Apr 18 13:30:01 volumio-salonw volumio[899]: info: Preparing to unpack .../16-gcc_4%3a8.3.0-1+rpi2_armhf.deb ... Apr 18 13:30:01 volumio-salonw volumio[899]: info: Unpacking gcc (4:8.3.0-1+rpi2) ... Apr 18 13:30:01 volumio-salonw volumio[899]: info: Selecting previously unselected package libstdc++-8-dev:armhf. Apr 18 13:30:01 volumio-salonw volumio[899]: info: Preparing to unpack .../17-libstdc++-8-dev_8.3.0-6+rpi1_armhf.deb ... Apr 18 13:30:01 volumio-salonw volumio[899]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:01 volumio-salonw volumio[899]: info: Apr 18 13:30:01 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:01 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:01 volumio-salonw volumio[899]: info: Apr 18 13:30:01 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:01 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:01 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:01 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:01 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:01 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93474,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:01 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:01 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:01 volumio-salonw volumio[899]: info: ------------------------------ 6ms Apr 18 13:30:03 volumio-salonw volumio[899]: info: Selecting previously unselected package g++-8. Apr 18 13:30:03 volumio-salonw volumio[899]: info: Preparing to unpack .../18-g++-8_8.3.0-6+rpi1_armhf.deb ... Apr 18 13:30:03 volumio-salonw volumio[899]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Apr 18 13:30:04 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:04 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:04 volumio-salonw volumio[899]: info: Apr 18 13:30:04 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:04 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:04 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:04 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:04 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:04 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":95853,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:04 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:04 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:04 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:04 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:04 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:04 volumio-salonw volumio[899]: info: ------------------------------ 4ms Apr 18 13:30:04 volumio-salonw volumio[899]: info: Selecting previously unselected package g++. Apr 18 13:30:04 volumio-salonw volumio[899]: info: Preparing to unpack .../19-g++_4%3a8.3.0-1+rpi2_armhf.deb ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Selecting previously unselected package make. Apr 18 13:30:04 volumio-salonw volumio[899]: info: Preparing to unpack .../20-make_4.2.1-1.2_armhf.deb ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Unpacking make (4.2.1-1.2) ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Selecting previously unselected package patch. Apr 18 13:30:04 volumio-salonw volumio[899]: info: Preparing to unpack .../21-patch_2.7.6-3+deb10u1_armhf.deb ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Unpacking patch (2.7.6-3+deb10u1) ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Selecting previously unselected package dpkg-dev. Apr 18 13:30:04 volumio-salonw volumio[899]: info: Preparing to unpack .../22-dpkg-dev_1.19.8_all.deb ... Apr 18 13:30:04 volumio-salonw volumio[899]: info: Unpacking dpkg-dev (1.19.8) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package build-essential. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../23-build-essential_12.6_armhf.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking build-essential (12.6) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package dh-python. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../24-dh-python_3.20190308_all.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking dh-python (3.20190308) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package libfakeroot:armhf. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../25-libfakeroot_1.23-1_armhf.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking libfakeroot:armhf (1.23-1) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package fakeroot. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../26-fakeroot_1.23-1_armhf.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking fakeroot (1.23-1) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package libalgorithm-diff-perl. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../27-libalgorithm-diff-perl_1.19.03-2_all.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../28-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package libalgorithm-merge-perl. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../29-libalgorithm-merge-perl_0.08-3_all.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Selecting previously unselected package libexpat1-dev:armhf. Apr 18 13:30:05 volumio-salonw volumio[899]: info: Preparing to unpack .../30-libexpat1-dev_2.2.6-2+deb10u7_armhf.deb ... Apr 18 13:30:05 volumio-salonw volumio[899]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Apr 18 13:30:06 volumio-salonw volumio[899]: info: Selecting previously unselected package libpython3.7:armhf. Apr 18 13:30:06 volumio-salonw volumio[899]: info: Preparing to unpack .../31-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... Apr 18 13:30:06 volumio-salonw volumio[899]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:06 volumio-salonw volumio[899]: info: Selecting previously unselected package libpython3.7-dev:armhf. Apr 18 13:30:06 volumio-salonw volumio[899]: info: Preparing to unpack .../32-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Apr 18 13:30:06 volumio-salonw volumio[899]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:07 volumio-salonw volumio[899]: info: Apr 18 13:30:07 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:07 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:07 volumio-salonw volumio[899]: info: Apr 18 13:30:07 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:07 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:07 volumio-salonw volumio[899]: info: ------------------------------ 3ms Apr 18 13:30:07 volumio-salonw volumio[899]: info: sendMpdCommand status took 2 milliseconds Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:07 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:07 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99474,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:07 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:07 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:07 volumio-salonw volumio[899]: info: ------------------------------ 11ms Apr 18 13:30:10 volumio-salonw volumio[899]: info: Selecting previously unselected package libpython3-dev:armhf. Apr 18 13:30:10 volumio-salonw volumio[899]: info: Preparing to unpack .../33-libpython3-dev_3.7.3-1_armhf.deb ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Selecting previously unselected package libxslt1.1:armhf. Apr 18 13:30:10 volumio-salonw volumio[899]: info: Preparing to unpack .../34-libxslt1.1_1.1.32-2.2~deb10u2_armhf.deb ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Unpacking libxslt1.1:armhf (1.1.32-2.2~deb10u2) ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Selecting previously unselected package python-libxml2. Apr 18 13:30:10 volumio-salonw volumio[899]: info: Preparing to unpack .../35-python-libxml2_2.9.4+dfsg1-7+deb10u6_armhf.deb ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Unpacking python-libxml2 (2.9.4+dfsg1-7+deb10u6) ... Apr 18 13:30:10 volumio-salonw volumio[899]: info: Apr 18 13:30:10 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:10 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:10 volumio-salonw volumio[899]: info: Apr 18 13:30:10 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:10 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:10 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:10 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:30:10 volumio-salonw volumio[899]: info: sendMpdCommand status took 2 milliseconds Apr 18 13:30:10 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:10 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:11 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 13:30:11 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:11 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:11 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102596,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:11 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:11 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:11 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:11 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:11 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:11 volumio-salonw volumio[899]: info: ------------------------------ 14ms Apr 18 13:30:11 volumio-salonw volumio[899]: info: Selecting previously unselected package python-libxslt1. Apr 18 13:30:11 volumio-salonw volumio[899]: info: Preparing to unpack .../36-python-libxslt1_1.1.32-2.2~deb10u2_armhf.deb ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Unpacking python-libxslt1 (1.1.32-2.2~deb10u2) ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Selecting previously unselected package python-pip-whl. Apr 18 13:30:11 volumio-salonw volumio[899]: info: Preparing to unpack .../37-python-pip-whl_18.1-5+rpt1_all.deb ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-asn1crypto. Apr 18 13:30:11 volumio-salonw volumio[899]: info: Preparing to unpack .../38-python3-asn1crypto_0.24.0-1_all.deb ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-cffi-backend. Apr 18 13:30:11 volumio-salonw volumio[899]: info: Preparing to unpack .../39-python3-cffi-backend_1.12.2-1_armhf.deb ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-crypto. Apr 18 13:30:11 volumio-salonw volumio[899]: info: Preparing to unpack .../40-python3-crypto_2.6.1-9+b1_armhf.deb ... Apr 18 13:30:11 volumio-salonw volumio[899]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-cryptography. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../41-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-dbus. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../42-python3-dbus_1.2.8-3_armhf.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-dbus (1.2.8-3) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3.7-dev. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../43-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-dev. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../44-python3-dev_3.7.3-1_armhf.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-dev (3.7.3-1) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-entrypoints. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../45-python3-entrypoints_0.3-1_all.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-entrypoints (0.3-1) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-gi. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../46-python3-gi_3.30.4-1_armhf.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-gi (3.30.4-1) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-secretstorage. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../47-python3-secretstorage_2.3.1-2_all.deb ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Unpacking python3-secretstorage (2.3.1-2) ... Apr 18 13:30:12 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-keyring. Apr 18 13:30:12 volumio-salonw volumio[899]: info: Preparing to unpack .../48-python3-keyring_17.1.1-1_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-keyring (17.1.1-1) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-keyrings.alt. Apr 18 13:30:13 volumio-salonw volumio[899]: info: Preparing to unpack .../49-python3-keyrings.alt_3.1.1-1_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-pip. Apr 18 13:30:13 volumio-salonw volumio[899]: info: Preparing to unpack .../50-python3-pip_18.1-5+rpt1_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-pip (18.1-5+rpt1) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-setuptools. Apr 18 13:30:13 volumio-salonw volumio[899]: info: Preparing to unpack .../51-python3-setuptools_40.8.0-1_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-setuptools (40.8.0-1) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-wheel. Apr 18 13:30:13 volumio-salonw volumio[899]: info: Preparing to unpack .../52-python3-wheel_0.32.3-2_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-wheel (0.32.3-2) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Selecting previously unselected package python3-xdg. Apr 18 13:30:13 volumio-salonw volumio[899]: info: Preparing to unpack .../53-python3-xdg_0.25-5_all.deb ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Unpacking python3-xdg (0.25-5) ... Apr 18 13:30:13 volumio-salonw volumio[899]: info: Apr 18 13:30:13 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:13 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:13 volumio-salonw volumio[899]: info: Apr 18 13:30:13 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:13 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:13 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:30:13 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:13 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:13 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:13 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105592,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:13 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:13 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:13 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:14 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:14 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:14 volumio-salonw volumio[899]: info: ------------------------------ 8ms Apr 18 13:30:14 volumio-salonw volumio[899]: info: Setting up python3-entrypoints (0.3-1) ... Apr 18 13:30:14 volumio-salonw volumio[899]: info: Setting up dh-python (3.20190308) ... Apr 18 13:30:14 volumio-salonw volumio[899]: info: Setting up libexpat1:armhf (2.2.6-2+deb10u7) ... Apr 18 13:30:14 volumio-salonw volumio[899]: info: Setting up python3-dbus (1.2.8-3) ... Apr 18 13:30:14 volumio-salonw volumio[899]: info: Setting up python3-setuptools (40.8.0-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up python3-xdg (0.25-5) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up bzip2 (1.0.6-9.2~deb10u2) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libpython3.7-minimal:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up python3-wheel (0.32.3-2) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libfakeroot:armhf (1.23-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up fakeroot (1.23-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Apr 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: 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 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libasan5:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u7) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up make (4.2.1-1.2) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libmpfr6:armhf (4.0.2-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up python3-gi (3.30.4-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up libmpc3:armhf (1.1.0-1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up patch (2.7.6-3+deb10u1) ... Apr 18 13:30:15 volumio-salonw volumio[899]: info: Setting up python3.7-minimal (3.7.3-2+deb10u7) ... Apr 18 13:30:16 volumio-salonw volumio[899]: info: Setting up libubsan1:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:16 volumio-salonw volumio[899]: info: Setting up python-libxml2 (2.9.4+dfsg1-7+deb10u6) ... Apr 18 13:30:16 volumio-salonw volumio[899]: info: Setting up libisl19:armhf (0.20-2) ... Apr 18 13:30:16 volumio-salonw volumio[899]: info: Apr 18 13:30:16 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:16 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:16 volumio-salonw volumio[899]: info: Apr 18 13:30:16 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:16 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:16 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:16 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:16 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:16 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108600,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:16 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:16 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:17 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up libxslt1.1:armhf (1.1.32-2.2~deb10u2) ... Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up python3-crypto (2.6.1-9+b1) ... Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up python-pip-whl (18.1-5+rpt1) ... Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up cpp-8 (8.3.0-6+rpi1) ... Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Apr 18 13:30:17 volumio-salonw volumio[899]: info: Setting up python3-asn1crypto (0.24.0-1) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up python3-cffi-backend (1.12.2-1) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up libpython3.7-stdlib:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up libpython3.7:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up python-libxslt1 (1.1.32-2.2~deb10u2) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up dpkg-dev (1.19.8) ... Apr 18 13:30:18 volumio-salonw volumio[899]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up python3-pip (18.1-5+rpt1) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up cpp (4:8.3.0-1+rpi2) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Setting up python3.7 (3.7.3-2+deb10u7) ... Apr 18 13:30:19 volumio-salonw volumio[899]: info: Apr 18 13:30:19 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:19 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:19 volumio-salonw volumio[899]: info: Apr 18 13:30:19 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:19 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:19 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:19 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:19 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:19 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:19 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:19 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111596,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:19 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:19 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:19 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:19 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:19 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:19 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:20 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:20 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:20 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:20 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:20 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:20 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:20 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:20 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:20 volumio-salonw volumio[899]: info: ------------------------------ 10ms Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up gcc (4:8.3.0-1+rpi2) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up python3-secretstorage (2.3.1-2) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up python3-dev (3.7.3-1) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up g++-8 (8.3.0-6+rpi1) ... Apr 18 13:30:20 volumio-salonw volumio[899]: info: Setting up python3-keyring (17.1.1-1) ... Apr 18 13:30:21 volumio-salonw volumio[899]: info: Setting up g++ (4:8.3.0-1+rpi2) ... Apr 18 13:30:21 volumio-salonw volumio[899]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Apr 18 13:30:21 volumio-salonw volumio[899]: info: Setting up build-essential (12.6) ... Apr 18 13:30:21 volumio-salonw volumio[899]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Apr 18 13:30:21 volumio-salonw volumio[899]: info: Processing triggers for mime-support (3.62) ... Apr 18 13:30:21 volumio-salonw sudo[17755]: pam_unix(sudo:session): session closed for user root Apr 18 13:30:21 volumio-salonw sudo[18691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/pip3 install streamlink Apr 18 13:30:21 volumio-salonw sudo[18691]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:30:22 volumio-salonw volumio[899]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Apr 18 13:30:22 volumio-salonw volumio[899]: info: Collecting streamlink Apr 18 13:30:22 volumio-salonw volumio[899]: info: Apr 18 13:30:22 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:22 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:22 volumio-salonw volumio[899]: info: Apr 18 13:30:22 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:22 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:22 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:22 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:22 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:22 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114594,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:22 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:22 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:22 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:23 volumio-salonw volumio[899]: info: Downloading https://files.pythonhosted.org/packages/cd/5f/3ccd2c5453900b868fb483253735f22eca61c6da125054e02cd999defa1b/streamlink-5.5.1-py3-none-any.whl (362kB) Apr 18 13:30:23 volumio-salonw volumio[899]: info: Collecting urllib3<3,>=1.26.0 (from streamlink) Apr 18 13:30:23 volumio-salonw volumio[899]: info: Downloading https://files.pythonhosted.org/packages/d2/b2/b157855192a68541a91ba7b2bbcb91f1b4faa51f8bae38d8005c034be524/urllib3-2.0.7-py3-none-any.whl (124kB) Apr 18 13:30:23 volumio-salonw volumio[899]: info: Collecting requests<3.0,>=2.26.0 (from streamlink) Apr 18 13:30:24 volumio-salonw volumio[899]: info: Downloading https://files.pythonhosted.org/packages/70/8e/0e2d847013cb52cd35b38c009bb167a1a26b2ce6cd6965bf26b47bc0bf44/requests-2.31.0-py3-none-any.whl (62kB) Apr 18 13:30:24 volumio-salonw volumio[899]: info: Collecting pycountry (from streamlink) Apr 18 13:30:24 volumio-salonw volumio[899]: info: Downloading https://www.piwheels.org/simple/pycountry/pycountry-22.3.5-py2.py3-none-any.whl (10.7MB) Apr 18 13:30:25 volumio-salonw volumio[899]: info: Apr 18 13:30:25 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:25 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:25 volumio-salonw volumio[899]: info: Apr 18 13:30:25 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:25 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:25 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:25 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:25 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:25 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":117601,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:25 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:25 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:26 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:30:28 volumio-salonw volumio[899]: info: Collecting lxml<5.0,>=4.6.4 (from streamlink) Apr 18 13:30:28 volumio-salonw volumio[899]: info: Apr 18 13:30:28 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:28 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:28 volumio-salonw volumio[899]: info: Apr 18 13:30:28 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:28 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:28 volumio-salonw volumio[899]: info: ------------------------------ 0ms Apr 18 13:30:28 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:28 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:28 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":120592,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:28 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:28 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:28 volumio-salonw volumio[899]: info: ------------------------------ 4ms Apr 18 13:30:29 volumio-salonw volumio[899]: info: Downloading https://files.pythonhosted.org/packages/84/14/c2070b5e37c650198de8328467dd3d1681e80986f81ba0fea04fc4ec9883/lxml-4.9.4.tar.gz (3.6MB) Apr 18 13:30:31 volumio-salonw volumio[899]: info: Complete output from command python setup.py egg_info: Apr 18 13:30:31 volumio-salonw volumio[899]: info: Building lxml version 4.9.4. Apr 18 13:30:31 volumio-salonw volumio[899]: info: Building without Cython. Apr 18 13:30:31 volumio-salonw volumio[899]: info: Error: Please make sure the libxml2 and libxslt development packages are installed. Apr 18 13:30:31 volumio-salonw volumio[899]: info: Apr 18 13:30:31 volumio-salonw volumio[899]: info: ---------------------------------------- Apr 18 13:30:31 volumio-salonw sudo[18691]: pam_unix(sudo:session): session closed for user root Apr 18 13:30:31 volumio-salonw sudo[17544]: pam_unix(sudo:session): session closed for user root Apr 18 13:30:31 volumio-salonw volumio[899]: info: Mixcloud plugin installed Apr 18 13:30:31 volumio-salonw volumio[899]: Plugin install end detected on script Apr 18 13:30:31 volumio-salonw volumio[899]: info: Install script completed Apr 18 13:30:31 volumio-salonw volumio[899]: info: Adding reference to registry Apr 18 13:30:31 volumio-salonw volumio[899]: info: Done installing plugin. Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 18 13:30:31 volumio-salonw volumio[899]: info: Folder /tmp/plugins removed Apr 18 13:30:31 volumio-salonw volumio[899]: info: Folder /tmp/downloaded_plugin.zip removed Apr 18 13:30:31 volumio-salonw volumio[899]: info: Folder /data/temp removed Apr 18 13:30:31 volumio-salonw volumio[899]: info: Apr 18 13:30:31 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:31 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:31 volumio-salonw volumio[899]: info: Apr 18 13:30:31 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:31 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:31 volumio-salonw volumio[899]: info: ------------------------------ 0ms Apr 18 13:30:31 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:31 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:31 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123600,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:31 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:31 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:31 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:34 volumio-salonw volumio[899]: info: Enabling plugin mixcloud Apr 18 13:30:34 volumio-salonw volumio[899]: info: Loading plugin "mixcloud"... Apr 18 13:30:34 volumio-salonw volumio[899]: info: PLUGIN START: mixcloud Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 18 13:30:34 volumio-salonw volumio[899]: info: [1713439834532] CoreMusicLibrary::Adding element Mixcloud Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 18 13:30:34 volumio-salonw volumio[899]: Cannot find translation for source QOBUZ Apr 18 13:30:34 volumio-salonw volumio[899]: Cannot find translation for source Bandcamp Discover Apr 18 13:30:34 volumio-salonw volumio[899]: Cannot find translation for source Mixcloud Apr 18 13:30:34 volumio-salonw volumio[899]: info: Done. Apr 18 13:30:34 volumio-salonw volumio[899]: info: Apr 18 13:30:34 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:34 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:34 volumio-salonw volumio[899]: info: Apr 18 13:30:34 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:34 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:34 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:34 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:34 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:34 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":126596,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:34 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:34 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:34 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:37 volumio-salonw volumio[899]: info: Apr 18 13:30:37 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:37 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:37 volumio-salonw volumio[899]: info: Apr 18 13:30:37 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:37 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:37 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:37 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:37 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:37 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129594,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:37 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:37 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:37 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:40 volumio-salonw volumio[899]: info: Apr 18 13:30:40 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:40 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:40 volumio-salonw volumio[899]: info: Apr 18 13:30:40 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:40 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:40 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:30:40 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:40 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:40 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132590,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:40 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:40 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:41 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:41 volumio-salonw volumio[899]: info: ------------------------------ 8ms Apr 18 13:30:43 volumio-salonw volumio[899]: info: Apr 18 13:30:43 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:43 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:43 volumio-salonw volumio[899]: info: Apr 18 13:30:43 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:43 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:43 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:30:43 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:43 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:43 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:43 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":135597,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:43 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:43 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:43 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:43 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:44 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:44 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:44 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:44 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:44 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:44 volumio-salonw volumio[899]: info: ------------------------------ 10ms Apr 18 13:30:46 volumio-salonw volumio[899]: info: Apr 18 13:30:46 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:46 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:46 volumio-salonw volumio[899]: info: Apr 18 13:30:46 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:46 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:46 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:46 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:30:46 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:46 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:46 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:46 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 18 13:30:46 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:47 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:47 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":138590,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:47 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:47 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:47 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:47 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:47 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:47 volumio-salonw volumio[899]: info: ------------------------------ 14ms Apr 18 13:30:49 volumio-salonw volumio[899]: info: Apr 18 13:30:49 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:49 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:49 volumio-salonw volumio[899]: info: Apr 18 13:30:49 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:49 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:49 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:49 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:49 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:49 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":141597,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:49 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:49 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:49 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:49 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:49 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:49 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:52 volumio-salonw volumio[899]: info: Apr 18 13:30:52 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:52 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:52 volumio-salonw volumio[899]: info: Apr 18 13:30:52 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:52 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:52 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:52 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:52 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:52 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":144594,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:52 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:52 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:52 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:52 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:52 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:52 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:55 volumio-salonw volumio[899]: info: Apr 18 13:30:55 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:55 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:55 volumio-salonw volumio[899]: info: Apr 18 13:30:55 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:55 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:55 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:55 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:55 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:55 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147590,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:55 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:55 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:55 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:55 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:55 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:55 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:30:57 volumio-salonw volumio[899]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 18 13:30:58 volumio-salonw volumio[899]: info: Apr 18 13:30:58 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:30:58 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:30:58 volumio-salonw volumio[899]: info: Apr 18 13:30:58 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:30:58 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:30:58 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:30:58 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:30:58 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:30:58 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":150598,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:30:58 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:30:58 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:58 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:30:58 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:30:58 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:30:58 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:00 volumio-salonw volumio[899]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 18 13:31:00 volumio-salonw volumio[899]: info: Folder /tmp/plugins removed Apr 18 13:31:00 volumio-salonw volumio[899]: info: Check plugin dependencies Apr 18 13:31:00 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 13:31:00 volumio-salonw volumio[899]: info: Checking if plugin already exists Apr 18 13:31:00 volumio-salonw volumio[899]: info: Rename folder Apr 18 13:31:00 volumio-salonw volumio[899]: info: Folder /tmp/downloaded_plugin.zip removed Apr 18 13:31:00 volumio-salonw volumio[899]: info: Move to category Apr 18 13:31:01 volumio-salonw volumio[899]: info: Checking if install.sh is present Apr 18 13:31:01 volumio-salonw volumio[899]: info: Executing install.sh Apr 18 13:31:01 volumio-salonw sudo[18824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Apr 18 13:31:01 volumio-salonw sudo[18824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:31:01 volumio-salonw volumio[899]: info: Installing Go-librespot Apr 18 13:31:01 volumio-salonw volumio[899]: info: Checking old vollibrespot installs Apr 18 13:31:01 volumio-salonw systemd[1]: Reloading. Apr 18 13:31:01 volumio-salonw systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 18 13:31:01 volumio-salonw volumio[899]: info: Dowloading daemon Apr 18 13:31:01 volumio-salonw volumio[899]: info: Apr 18 13:31:01 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:01 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:01 volumio-salonw volumio[899]: info: Apr 18 13:31:01 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:01 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:01 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:01 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:01 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:01 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":153595,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:01 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:01 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:01 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:01 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:02 volumio-salonw volumio[899]: info: Creating Start Script Apr 18 13:31:02 volumio-salonw systemd[1]: Reloading. Apr 18 13:31:02 volumio-salonw systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 18 13:31:02 volumio-salonw sudo[18824]: pam_unix(sudo:session): session closed for user root Apr 18 13:31:02 volumio-salonw volumio[899]: Plugin install end detected on script Apr 18 13:31:02 volumio-salonw volumio[899]: info: Install script completed Apr 18 13:31:02 volumio-salonw volumio[899]: info: Adding reference to registry Apr 18 13:31:02 volumio-salonw volumio[899]: info: Done installing plugin. Apr 18 13:31:02 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 18 13:31:02 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 18 13:31:02 volumio-salonw volumio[899]: info: Folder /tmp/plugins removed Apr 18 13:31:02 volumio-salonw volumio[899]: info: Folder /tmp/downloaded_plugin.zip removed Apr 18 13:31:02 volumio-salonw volumio[899]: info: Folder /data/temp removed Apr 18 13:31:04 volumio-salonw volumio[899]: info: Enabling plugin spop Apr 18 13:31:04 volumio-salonw volumio[899]: info: Loading plugin "spop"... Apr 18 13:31:05 volumio-salonw volumio[899]: info: PLUGIN START: spop Apr 18 13:31:05 volumio-salonw volumio[899]: info: Creating Spotify config file Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 13:31:05 volumio-salonw volumio[899]: info: Done. Apr 18 13:31:05 volumio-salonw volumio[899]: info: Apr 18 13:31:05 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:05 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:05 volumio-salonw volumio[899]: info: Apr 18 13:31:05 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:05 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:05 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:05 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:05 volumio-salonw volumio[899]: info: Spotify config file written Apr 18 13:31:05 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:05 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156723,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:05 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:05 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:05 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:05 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:05 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:05 volumio-salonw volumio[899]: info: ------------------------------ 16ms Apr 18 13:31:05 volumio-salonw sudo[18879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 18 13:31:05 volumio-salonw sudo[18879]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 13:31:05 volumio-salonw systemd[1]: Started go-librespot Daemon. Apr 18 13:31:05 volumio-salonw go-librespot[18883]: Librespot-go daemon starting... Apr 18 13:31:05 volumio-salonw sudo[18879]: pam_unix(sudo:session): session closed for user root Apr 18 13:31:05 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:05+02:00" level=info msg="generated new device id: dd953d71a105668d928575602ad08f4d149c7744" Apr 18 13:31:05 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Apr 18 13:31:05 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 18 13:31:05 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 18 13:31:05 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:05+02:00" level=debug msg="zeroconf server listening on port 36593" Apr 18 13:31:07 volumio-salonw volumio[899]: info: Apr 18 13:31:07 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:07 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:07 volumio-salonw volumio[899]: info: Apr 18 13:31:07 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:07 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:07 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:07 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:07 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:07 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159594,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:07 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:07 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:07 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:07 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:31:08 volumio-salonw volumio[899]: info: go-librespot daemon successfully initialized Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: Retrieving Cloud Streaming UI Apr 18 13:31:09 volumio-salonw volumio[899]: info: Getting Tidal Cloud Configuration Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: Getting Qobuz Cloud Configuration Apr 18 13:31:09 volumio-salonw volumio[899]: info: Asking plugin for UI Config Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: Getting Spotify Cloud Configuration Apr 18 13:31:09 volumio-salonw volumio[899]: info: Asking plugin for UI Config Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: Saving Spotify Acccount Apr 18 13:31:09 volumio-salonw volumio[899]: info: Got it Apr 18 13:31:09 volumio-salonw volumio[899]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 18 13:31:09 volumio-salonw volumio[899]: info: Got Tidal Cloud Configuration Apr 18 13:31:09 volumio-salonw volumio[899]: info: Got it Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioGetBrowseSources Apr 18 13:31:09 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 18 13:31:10 volumio-salonw volumio[899]: info: Apr 18 13:31:10 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:10 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:10 volumio-salonw volumio[899]: info: Apr 18 13:31:10 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:10 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:10 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:10 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:10 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:10 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162590,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:10 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:10 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:10 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:10 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:10 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:10 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:31:11 volumio-salonw volumio[899]: info: Initializing connection to go-librespot Websocket Apr 18 13:31:11 volumio-salonw go-librespot[18883]: time="2024-04-18T13:31:11+02:00" level=debug msg="new websocket client" Apr 18 13:31:11 volumio-salonw volumio[899]: info: Connection to go-librespot Websocket established Apr 18 13:31:11 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 18 13:31:11 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:31:13 volumio-salonw volumio[899]: info: Apr 18 13:31:13 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:13 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:13 volumio-salonw volumio[899]: info: Apr 18 13:31:13 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:13 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:13 volumio-salonw volumio[899]: info: ------------------------------ 0ms Apr 18 13:31:13 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:13 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:13 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":165586,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:13 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:13 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:13 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:13 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:14 volumio-salonw volumio[899]: info: Getting Spotify volume Apr 18 13:31:14 volumio-salonw volumio[899]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Apr 18 13:31:14 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioGetState Apr 18 13:31:14 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:14 volumio-salonw volumio[899]: SPOTIFY: SPOTIFY VOLUME undefined Apr 18 13:31:14 volumio-salonw volumio[899]: SPOTIFY: VOLUMIO VOLUME 100 Apr 18 13:31:14 volumio-salonw volumio[899]: info: Aligning Spotify Volume to Volumio Volume Apr 18 13:31:14 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioGetState Apr 18 13:31:14 volumio-salonw volumio[899]: info: Setting Spotify Volume from Volumio: 100 Apr 18 13:31:15 volumio-salonw volumio[899]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Apr 18 13:31:15 volumio-salonw volumio[899]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 13:31:16 volumio-salonw volumio[899]: info: Error browsing device NAS-ALAINDIV for shares : Error: Command failed: echo volumio | smbclient -L NAS-ALAINDIV.local. Apr 18 13:31:16 volumio-salonw volumio[899]: Unable to initialize messaging context Apr 18 13:31:16 volumio-salonw volumio[899]: do_connect: Connection to NAS-ALAINDIV.local. failed (Error NT_STATUS_HOST_UNREACHABLE) Apr 18 13:31:16 volumio-salonw volumio[899]: info: Apr 18 13:31:16 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:16 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:16 volumio-salonw volumio[899]: info: Apr 18 13:31:16 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:16 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:16 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:16 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:16 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:16 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":168595,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:16 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:16 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:16 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:16 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:16 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:17 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:17 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 18 13:31:19 volumio-salonw volumio[899]: info: Apr 18 13:31:19 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:19 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:19 volumio-salonw volumio[899]: info: Apr 18 13:31:19 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:19 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:19 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:19 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:19 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:19 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":171591,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:19 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:19 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:19 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:19 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:19 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:19 volumio-salonw volumio[899]: info: ------------------------------ 9ms Apr 18 13:31:19 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:20 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:20 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 18 13:31:20 volumio-salonw volumio[899]: info: CURURI: music-library Apr 18 13:31:20 volumio-salonw volumio[899]: error: Failed LSINFO: null Apr 18 13:31:20 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:22 volumio-salonw volumio[899]: info: Apr 18 13:31:22 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:22 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:22 volumio-salonw volumio[899]: info: Apr 18 13:31:22 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:22 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:22 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:22 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:22 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:22 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174587,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:22 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:22 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:22 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:22 volumio-salonw volumio[899]: info: ------------------------------ 6ms Apr 18 13:31:22 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:23 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:24 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: mixcloud , handleBrowseUri Apr 18 13:31:24 volumio-salonw volumio[899]: info: [mixcloud] browseUri: mixcloud Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:24 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:25 volumio-salonw volumio[899]: info: Apr 18 13:31:25 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:25 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:25 volumio-salonw volumio[899]: info: Apr 18 13:31:25 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:25 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:25 volumio-salonw volumio[899]: info: ------------------------------ 3ms Apr 18 13:31:25 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:25 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:25 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":177590,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:25 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:25 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:25 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:25 volumio-salonw volumio[899]: info: ------------------------------ 10ms Apr 18 13:31:26 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:26 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:28 volumio-salonw volumio[899]: info: Apr 18 13:31:28 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:28 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:28 volumio-salonw volumio[899]: info: Apr 18 13:31:28 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:28 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:28 volumio-salonw volumio[899]: info: ------------------------------ 3ms Apr 18 13:31:28 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:28 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:28 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":180587,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:28 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:28 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:28 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:28 volumio-salonw volumio[899]: info: ------------------------------ 10ms Apr 18 13:31:29 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:29 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:29 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Apr 18 13:31:29 volumio-salonw volumio[899]: info: [bandcamp-browse] browseUri: bandcamp Apr 18 13:31:31 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:31 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:31 volumio-salonw volumio[899]: info: Apr 18 13:31:31 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:31 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:31 volumio-salonw volumio[899]: info: Apr 18 13:31:31 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:31 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:31 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:31 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:31 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:31 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183595,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:31 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:31 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:31 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:31 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:31 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:32 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:33 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:34 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:34 volumio-salonw volumio[899]: info: Apr 18 13:31:34 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:34 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:34 volumio-salonw volumio[899]: info: Apr 18 13:31:34 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:34 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:34 volumio-salonw volumio[899]: info: ------------------------------ 0ms Apr 18 13:31:34 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:34 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:34 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186592,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:34 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:34 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:34 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:34 volumio-salonw volumio[899]: info: ------------------------------ 6ms Apr 18 13:31:34 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:35 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:37 volumio-salonw volumio[899]: error: Error browsing0:Error: Did not get any response from server: Apr 18 13:31:37 volumio-salonw volumio[899]: error: Failed to execute browseSource: Apr 18 13:31:37 volumio-salonw volumio[899]: info: Apr 18 13:31:37 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:37 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:37 volumio-salonw volumio[899]: info: Apr 18 13:31:37 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:37 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:37 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:31:37 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:37 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:37 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189588,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:37 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:37 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:37 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:37 volumio-salonw volumio[899]: info: ------------------------------ 9ms Apr 18 13:31:37 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:38 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:40 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:40 volumio-salonw volumio[899]: info: Apr 18 13:31:40 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:40 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:40 volumio-salonw volumio[899]: info: Apr 18 13:31:40 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:40 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:40 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:31:40 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:40 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:40 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":192345,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:40 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:40 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:40 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:40 volumio-salonw volumio[899]: info: ------------------------------ 7ms Apr 18 13:31:40 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:40 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:43 volumio-salonw volumio[899]: info: Apr 18 13:31:43 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:43 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:43 volumio-salonw volumio[899]: info: Apr 18 13:31:43 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:43 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:43 volumio-salonw volumio[899]: info: ------------------------------ 2ms Apr 18 13:31:43 volumio-salonw volumio[899]: info: sendMpdCommand status took 1 milliseconds Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:43 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:43 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":195588,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:43 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:43 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:43 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:43 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:43 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:43 volumio-salonw volumio[899]: info: ------------------------------ 10ms Apr 18 13:31:43 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:44 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:44 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:44 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:46 volumio-salonw volumio[899]: info: Preload queue cleared Apr 18 13:31:46 volumio-salonw volumio[899]: info: Apr 18 13:31:46 volumio-salonw volumio[899]: ---------------------------- MPD announces system playlist update Apr 18 13:31:46 volumio-salonw volumio[899]: info: Ignoring MPD Status Update Apr 18 13:31:46 volumio-salonw volumio[899]: info: Apr 18 13:31:46 volumio-salonw volumio[899]: ---------------------------- MPD announces state update: player Apr 18 13:31:46 volumio-salonw volumio[899]: info: ControllerMpd::getState Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand status Apr 18 13:31:46 volumio-salonw volumio[899]: info: ------------------------------ 1ms Apr 18 13:31:46 volumio-salonw volumio[899]: info: sendMpdCommand status took 0 milliseconds Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: ControllerMpd::parseState Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 13:31:46 volumio-salonw volumio[899]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: ControllerMpd::parseTrackInfo Apr 18 13:31:46 volumio-salonw volumio[899]: info: ControllerMpd::pushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::servicePushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: CorePlayQueue::getTrack 0 Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198584,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"CHICO SCIENCE - Maracatu Atômico","artist":"stream","album":null,"uri":"https://stream10.xdevel.com/audio12s976748-1798/stream/icecast.audio","trackType":"audio"} Apr 18 13:31:46 volumio-salonw volumio[899]: verbose: CURRENT POSITION 0 Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreStateMachine::syncState stateService play Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreStateMachine::syncState currentStatus play Apr 18 13:31:46 volumio-salonw volumio[899]: info: Received an update from plugin. extracting info from payload Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:46 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreStateMachine::pushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: CoreCommandRouter::volumioPushState Apr 18 13:31:46 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output update for this device Apr 18 13:31:46 volumio-salonw volumio[899]: info: MRS: Pushing multiroomSync output Apr 18 13:31:46 volumio-salonw volumio[899]: info: ------------------------------ 5ms Apr 18 13:31:46 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:47 volumio-salonw volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 13:31:49 volumio-salonw volumio[899]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 18 13:31:49 volumio-salonw volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:31:49 volumio-salonw volumio[899]: TypeError: Cannot read property 'length' of undefined Apr 18 13:31:49 volumio-salonw volumio[899]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 18 13:31:49 volumio-salonw volumio[899]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 18 13:31:49 volumio-salonw volumio[899]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 18 13:31:49 volumio-salonw volumio[899]: at Parser.emit (events.js:315:20) Apr 18 13:31:49 volumio-salonw volumio[899]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 18 13:31:49 volumio-salonw volumio[899]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 18 13:31:49 volumio-salonw volumio[899]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 18 13:31:49 volumio-salonw volumio[899]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 18 13:31:49 volumio-salonw volumio[899]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 18 13:31:49 volumio-salonw volumio[899]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 18 13:31:49 volumio-salonw volumio[899]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 18 13:31:49 volumio-salonw volumio[899]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 18 13:31:49 volumio-salonw volumio[899]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 18 13:31:49 volumio-salonw volumio[899]: at IncomingMessage.emit (events.js:327:22) Apr 18 13:31:49 volumio-salonw volumio[899]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 18 13:31:49 volumio-salonw volumio[899]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 18 13:31:49 volumio-salonw volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 13:31:50 volumio-salonw sudo[19088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 13:30 Apr 18 13:31:50 volumio-salonw sudo[19088]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"