-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Wed 2024-05-15 23:50:00 +08. -- May 15 23:48:00 volumio2 volumio[751]: info: Selecting previously unselected package libpython3.7-dev:armhf. May 15 23:48:00 volumio2 volumio[751]: info: Preparing to unpack .../27-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... May 15 23:48:00 volumio2 volumio[751]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... May 15 23:48:02 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:02 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:12 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:12 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:12 volumio2 volumio[751]: info: Listing playlists May 15 23:48:12 volumio2 volumio[751]: info: Listing playlists May 15 23:48:14 volumio2 volumio[751]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 15 23:48:14 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:16 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:17 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:48:17 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:17 volumio2 sudo[4204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:48:17 volumio2 sudo[4205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:48:17 volumio2 sudo[4204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:48:17 volumio2 sudo[4205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:48:17 volumio2 sudo[4204]: pam_unix(sudo:session): session closed for user root May 15 23:48:17 volumio2 volumio[751]: info: MPD Permissions set May 15 23:48:17 volumio2 systemd[1]: Stopping Music Player Daemon... May 15 23:48:17 volumio2 volumio[751]: error: Upnp client error: Error: This socket has been ended by the other party May 15 23:48:19 volumio2 volumio[751]: info: CoreCommandRouter::volumioStop May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::stop May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::stPlaybackTimer May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::updateTrackBlock May 15 23:48:19 volumio2 volumio[751]: info: CorePlayQueue::getTrackBlock May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:48:19 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:19 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:48:19 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:48:19 volumio2 volumio[751]: info: CoreStateMachine::serviceStop May 15 23:48:19 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:19 volumio2 volumio[751]: info: CoreCommandRouter::serviceStop May 15 23:48:19 volumio2 volumio[751]: info: [1715788099136] ControllerWebradio::stop May 15 23:48:21 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand stop May 15 23:48:20 volumio2 ntpd[576]: Soliciting pool server 2001:e68:58bc:b200::123 May 15 23:48:21 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:21 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:21 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:21 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:21 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:21 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:21 volumio2 volumio[751]: info: CoreCommandRouter::volumioStop May 15 23:48:21 volumio2 volumio[751]: info: CoreStateMachine::stop May 15 23:48:21 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:21 volumio2 volumio[751]: info: Selecting previously unselected package libpython3-dev:armhf. May 15 23:48:21 volumio2 volumio[751]: info: Preparing to unpack .../28-libpython3-dev_3.7.3-1_armhf.deb ... May 15 23:48:21 volumio2 volumio[751]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... May 15 23:48:21 volumio2 systemd[1]: mpd.service: Succeeded. May 15 23:48:21 volumio2 systemd[1]: Stopped Music Player Daemon. May 15 23:48:21 volumio2 systemd[1]: Starting Music Player Daemon... May 15 23:48:21 volumio2 volumio[751]: info: Selecting previously unselected package python-pip-whl. May 15 23:48:21 volumio2 volumio[751]: info: Preparing to unpack .../29-python-pip-whl_18.1-5+rpt1_all.deb ... May 15 23:48:21 volumio2 volumio[751]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... May 15 23:48:22 volumio2 volumio[751]: info: Selecting previously unselected package python3-multidict. May 15 23:48:22 volumio2 volumio[751]: info: Preparing to unpack .../30-python3-multidict_4.5.2-1_armhf.deb ... May 15 23:48:22 volumio2 volumio[751]: info: Unpacking python3-multidict (4.5.2-1) ... May 15 23:48:22 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:22 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:22 volumio2 volumio[751]: info: CoreCommandRouter::volumioPlay May 15 23:48:22 volumio2 volumio[751]: info: CoreStateMachine::play index undefined May 15 23:48:22 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:22 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:22 volumio2 volumio[751]: info: CoreStateMachine::startPlaybackTimer May 15 23:48:22 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:22 volumio2 volumio[751]: info: [1715788102952] ControllerWebradio::clearAddPlayTrack May 15 23:48:22 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand stop May 15 23:48:22 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:22 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:22 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:22 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:22 volumio2 volumio[751]: error: MPD error: Error: This socket has been ended by the other party May 15 23:48:22 volumio2 volumio[751]: error: This socket has been ended by the other party {"code":"EPIPE"} May 15 23:48:23 volumio2 volumio[751]: info: Selecting previously unselected package python3-yarl. May 15 23:48:23 volumio2 volumio[751]: info: Preparing to unpack .../31-python3-yarl_1.3.0-1_armhf.deb ... May 15 23:48:23 volumio2 volumio[751]: info: Unpacking python3-yarl (1.3.0-1) ... May 15 23:48:23 volumio2 volumio[751]: info: Selecting previously unselected package python3-async-timeout. May 15 23:48:23 volumio2 volumio[751]: info: Preparing to unpack .../32-python3-async-timeout_3.0.1-1_all.deb ... May 15 23:48:23 volumio2 volumio[751]: info: Unpacking python3-async-timeout (3.0.1-1) ... May 15 23:48:23 volumio2 volumio[751]: info: Selecting previously unselected package python3-attr. May 15 23:48:23 volumio2 volumio[751]: info: Preparing to unpack .../33-python3-attr_18.2.0-1_all.deb ... May 15 23:48:23 volumio2 volumio[751]: info: Unpacking python3-attr (18.2.0-1) ... May 15 23:48:23 volumio2 volumio[751]: info: Selecting previously unselected package python3-aiohttp. May 15 23:48:23 volumio2 volumio[751]: info: Preparing to unpack .../34-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... May 15 23:48:23 volumio2 volumio[751]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... May 15 23:48:25 volumio2 mpd[4222]: May 15 23:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:48:25 volumio2 volumio[751]: Upnp client error: Error: This socket has been ended by the other party May 15 23:48:25 volumio2 systemd[1]: Started Music Player Daemon. May 15 23:48:25 volumio2 volumio[751]: Upnp client error: Error: This socket has been ended by the other party May 15 23:48:25 volumio2 sudo[4205]: pam_unix(sudo:session): session closed for user root May 15 23:48:25 volumio2 volumio[751]: error: updateQueue error: null May 15 23:48:26 volumio2 volumio[751]: info: Selecting previously unselected package python3-asn1crypto. May 15 23:48:26 volumio2 volumio[751]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... May 15 23:48:26 volumio2 volumio[751]: info: Unpacking python3-asn1crypto (0.24.0-1) ... May 15 23:48:26 volumio2 volumio[751]: info: Selecting previously unselected package python3-cffi-backend. May 15 23:48:26 volumio2 volumio[751]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... May 15 23:48:26 volumio2 volumio[751]: info: Unpacking python3-cffi-backend (1.12.2-1) ... May 15 23:48:27 volumio2 volumio[751]: info: Selecting previously unselected package python3-crypto. May 15 23:48:27 volumio2 volumio[751]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... May 15 23:48:27 volumio2 volumio[751]: info: Unpacking python3-crypto (2.6.1-9+b1) ... May 15 23:48:28 volumio2 volumio[751]: info: Selecting previously unselected package python3-cryptography. May 15 23:48:28 volumio2 volumio[751]: info: Preparing to unpack .../38-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... May 15 23:48:28 volumio2 volumio[751]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... May 15 23:48:29 volumio2 volumio[751]: info: Selecting previously unselected package python3-dbus. May 15 23:48:29 volumio2 volumio[751]: info: Preparing to unpack .../39-python3-dbus_1.2.8-3_armhf.deb ... May 15 23:48:29 volumio2 volumio[751]: info: Unpacking python3-dbus (1.2.8-3) ... May 15 23:48:29 volumio2 volumio[751]: info: CoreCommandRouter::volumioPlay May 15 23:48:29 volumio2 volumio[751]: info: CoreStateMachine::play index undefined May 15 23:48:29 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:29 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:29 volumio2 volumio[751]: info: CoreStateMachine::startPlaybackTimer May 15 23:48:29 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:29 volumio2 volumio[751]: info: [1715788109408] ControllerWebradio::clearAddPlayTrack May 15 23:48:29 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand stop May 15 23:48:29 volumio2 volumio[751]: info: sendMpdCommand stop took 1 milliseconds May 15 23:48:29 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand clear May 15 23:48:29 volumio2 volumio[751]: info: May 15 23:48:29 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:48:29 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:48:29 volumio2 volumio[751]: info: sendMpdCommand clear took 2 milliseconds May 15 23:48:29 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand load "https://mediaprima.rastream.com/mediaprima-onefm" May 15 23:48:29 volumio2 volumio[751]: error: updateQueue error: null May 15 23:48:29 volumio2 volumio[751]: info: ------------------------------ 4ms May 15 23:48:29 volumio2 volumio[751]: info: Selecting previously unselected package python3.7-dev. May 15 23:48:29 volumio2 volumio[751]: info: Preparing to unpack .../40-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... May 15 23:48:29 volumio2 volumio[751]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... May 15 23:48:29 volumio2 volumio[751]: info: Selecting previously unselected package python3-dev. May 15 23:48:29 volumio2 volumio[751]: info: Preparing to unpack .../41-python3-dev_3.7.3-1_armhf.deb ... May 15 23:48:29 volumio2 volumio[751]: info: Unpacking python3-dev (3.7.3-1) ... May 15 23:48:30 volumio2 volumio[751]: info: Selecting previously unselected package python3-entrypoints. May 15 23:48:30 volumio2 volumio[751]: info: Preparing to unpack .../42-python3-entrypoints_0.3-1_all.deb ... May 15 23:48:30 volumio2 volumio[751]: info: Unpacking python3-entrypoints (0.3-1) ... May 15 23:48:30 volumio2 volumio[751]: info: Selecting previously unselected package python3-gi. May 15 23:48:30 volumio2 volumio[751]: info: Preparing to unpack .../43-python3-gi_3.30.4-1_armhf.deb ... May 15 23:48:30 volumio2 volumio[751]: info: Unpacking python3-gi (3.30.4-1) ... May 15 23:48:30 volumio2 volumio[751]: info: Selecting previously unselected package python3-secretstorage. May 15 23:48:30 volumio2 volumio[751]: info: Preparing to unpack .../44-python3-secretstorage_2.3.1-2_all.deb ... May 15 23:48:30 volumio2 volumio[751]: info: Unpacking python3-secretstorage (2.3.1-2) ... May 15 23:48:31 volumio2 volumio[751]: info: Selecting previously unselected package python3-keyring. May 15 23:48:31 volumio2 volumio[751]: info: Preparing to unpack .../45-python3-keyring_17.1.1-1_all.deb ... May 15 23:48:31 volumio2 volumio[751]: info: Unpacking python3-keyring (17.1.1-1) ... May 15 23:48:31 volumio2 volumio[751]: info: Selecting previously unselected package python3-keyrings.alt. May 15 23:48:31 volumio2 volumio[751]: info: Preparing to unpack .../46-python3-keyrings.alt_3.1.1-1_all.deb ... May 15 23:48:31 volumio2 volumio[751]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... May 15 23:48:31 volumio2 volumio[751]: info: Selecting previously unselected package python3-pip. May 15 23:48:31 volumio2 volumio[751]: info: Preparing to unpack .../47-python3-pip_18.1-5+rpt1_all.deb ... May 15 23:48:31 volumio2 volumio[751]: info: Unpacking python3-pip (18.1-5+rpt1) ... May 15 23:48:32 volumio2 volumio[751]: info: Selecting previously unselected package python3-setuptools. May 15 23:48:32 volumio2 volumio[751]: info: Preparing to unpack .../48-python3-setuptools_40.8.0-1_all.deb ... May 15 23:48:32 volumio2 volumio[751]: info: Unpacking python3-setuptools (40.8.0-1) ... May 15 23:48:32 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:32 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:32 volumio2 volumio[751]: info: Listing playlists May 15 23:48:32 volumio2 volumio[751]: info: Listing playlists May 15 23:48:33 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand add "https://mediaprima.rastream.com/mediaprima-onefm" May 15 23:48:33 volumio2 volumio[751]: info: May 15 23:48:33 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:48:33 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:48:33 volumio2 volumio[751]: info: sendMpdCommand add "https://mediaprima.rastream.com/mediaprima-onefm" took 2 milliseconds May 15 23:48:33 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 23:48:33 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand play May 15 23:48:33 volumio2 volumio[751]: Upnp client error: Error: This socket has been ended by the other party May 15 23:48:33 volumio2 volumio[751]: info: ------------------------------ 5ms May 15 23:48:33 volumio2 volumio[751]: info: sendMpdCommand play took 3 milliseconds May 15 23:48:33 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:33 volumio2 volumio[751]: info: Selecting previously unselected package python3-wheel. May 15 23:48:33 volumio2 volumio[751]: info: Preparing to unpack .../49-python3-wheel_0.32.3-2_all.deb ... May 15 23:48:33 volumio2 volumio[751]: info: Unpacking python3-wheel (0.32.3-2) ... May 15 23:48:33 volumio2 volumio[751]: info: Selecting previously unselected package python3-xdg. May 15 23:48:33 volumio2 volumio[751]: info: Preparing to unpack .../50-python3-xdg_0.25-5_all.deb ... May 15 23:48:33 volumio2 volumio[751]: info: Unpacking python3-xdg (0.25-5) ... May 15 23:48:34 volumio2 volumio[751]: info: Setting up python3-entrypoints (0.3-1) ... May 15 23:48:35 volumio2 volumio[751]: info: Setting up dh-python (3.20190308) ... May 15 23:48:36 volumio2 volumio[751]: info: May 15 23:48:36 volumio2 volumio[751]: ---------------------------- MPD announces state update: player May 15 23:48:36 volumio2 volumio[751]: info: ControllerMpd::getState May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand status May 15 23:48:36 volumio2 volumio[751]: info: May 15 23:48:36 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:48:36 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:48:36 volumio2 volumio[751]: info: May 15 23:48:36 volumio2 volumio[751]: ---------------------------- MPD announces state update: player May 15 23:48:36 volumio2 volumio[751]: info: sendMpdCommand status took 42 milliseconds May 15 23:48:36 volumio2 volumio[751]: info: ControllerMpd::getState May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand status May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::parseState May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 23:48:36 volumio2 volumio[751]: info: ------------------------------ 14ms May 15 23:48:36 volumio2 volumio[751]: info: sendMpdCommand status took 4 milliseconds May 15 23:48:36 volumio2 volumio[751]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::parseState May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::parseTrackInfo May 15 23:48:36 volumio2 volumio[751]: info: ControllerMpd::pushState May 15 23:48:36 volumio2 volumio[751]: info: CoreCommandRouter::servicePushState May 15 23:48:36 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:36 volumio2 volumio[751]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"GIGI炎明熹（YAN MING XI） - CRYSTAL CLEAR - 电视剧《新闻女王》片尾曲","artist":null,"album":null,"uri":"https://mediaprima.rastream.com/mediaprima-onefm","trackType":""} May 15 23:48:36 volumio2 volumio[751]: verbose: CURRENT POSITION 0 May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::syncState stateService play May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::syncState currentStatus stop May 15 23:48:36 volumio2 volumio[751]: info: ------------------------------ 59ms May 15 23:48:36 volumio2 volumio[751]: info: sendMpdCommand playlistinfo took 5 milliseconds May 15 23:48:36 volumio2 volumio[751]: verbose: ControllerMpd::parseTrackInfo May 15 23:48:36 volumio2 volumio[751]: info: ControllerMpd::pushState May 15 23:48:36 volumio2 volumio[751]: info: CoreCommandRouter::servicePushState May 15 23:48:36 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:36 volumio2 volumio[751]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"GIGI炎明熹（YAN MING XI） - CRYSTAL CLEAR - 电视剧《新闻女王》片尾曲","artist":null,"album":null,"uri":"https://mediaprima.rastream.com/mediaprima-onefm","trackType":""} May 15 23:48:36 volumio2 volumio[751]: verbose: CURRENT POSITION 0 May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::syncState stateService play May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::syncState currentStatus play May 15 23:48:36 volumio2 volumio[751]: info: Received an update from plugin. extracting info from payload May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:48:36 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:48:36 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:48:36 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:48:36 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:48:36 volumio2 volumio[751]: info: ------------------------------ 67ms May 15 23:48:36 volumio2 volumio[751]: info: Setting up python3-attr (18.2.0-1) ... May 15 23:48:37 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetQueue May 15 23:48:37 volumio2 volumio[751]: info: CoreStateMachine::getQueue May 15 23:48:37 volumio2 volumio[751]: info: CorePlayQueue::getQueue May 15 23:48:37 volumio2 volumio[751]: info: Setting up python3-dbus (1.2.8-3) ... May 15 23:48:38 volumio2 volumio[751]: info: Setting up python3-setuptools (40.8.0-1) ... May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 23:48:40 volumio2 volumio[751]: info: Getting Alsa Cards List without I2S DAC May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 23:48:40 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 23:48:40 volumio2 volumio[751]: info: Setting up libpython3.7:armhf (3.7.3-2+deb10u7) ... May 15 23:48:40 volumio2 volumio[751]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... May 15 23:48:40 volumio2 volumio[751]: info: Setting up python3-xdg (0.25-5) ... May 15 23:48:41 volumio2 volumio[751]: info: Setting up bzip2 (1.0.6-9.2~deb10u2) ... May 15 23:48:41 volumio2 volumio[751]: info: Setting up python3-wheel (0.32.3-2) ... May 15 23:48:42 volumio2 volumio[751]: info: Setting up python3-multidict (4.5.2-1) ... May 15 23:48:42 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:43 volumio2 volumio[751]: info: Setting up libfakeroot:armhf (1.23-1) ... May 15 23:48:43 volumio2 volumio[751]: info: Setting up python3-async-timeout (3.0.1-1) ... May 15 23:48:44 volumio2 volumio[751]: info: Setting up fakeroot (1.23-1) ... May 15 23:48:44 volumio2 volumio[751]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: 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 May 15 23:48:44 volumio2 volumio[751]: info: Setting up libasan5:armhf (8.3.0-6+rpi1) ... May 15 23:48:44 volumio2 volumio[751]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u7) ... May 15 23:48:44 volumio2 volumio[751]: info: Setting up make (4.2.1-1.2) ... May 15 23:48:44 volumio2 volumio[751]: info: Setting up libmpfr6:armhf (4.0.2-1) ... May 15 23:48:44 volumio2 volumio[751]: info: Setting up python3-gi (3.30.4-1) ... May 15 23:48:46 volumio2 volumio[751]: info: Setting up libmpc3:armhf (1.1.0-1) ... May 15 23:48:46 volumio2 volumio[751]: info: Setting up patch (2.7.6-3+deb10u1) ... May 15 23:48:46 volumio2 volumio[751]: info: Setting up libubsan1:armhf (8.3.0-6+rpi1) ... May 15 23:48:46 volumio2 volumio[751]: info: Setting up libisl19:armhf (0.20-2) ... May 15 23:48:46 volumio2 volumio[751]: info: Setting up python3-crypto (2.6.1-9+b1) ... May 15 23:48:47 volumio2 volumio[751]: info: Setting up python-pip-whl (18.1-5+rpt1) ... May 15 23:48:48 volumio2 volumio[751]: info: Setting up cpp-8 (8.3.0-6+rpi1) ... May 15 23:48:48 volumio2 volumio[751]: info: Setting up python3-yarl (1.3.0-1) ... May 15 23:48:48 volumio2 volumio[751]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 sudo[4679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:48:48 volumio2 sudo[4678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:48:48 volumio2 sudo[4679]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:48:48 volumio2 sudo[4678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:48:48 volumio2 sudo[4678]: pam_unix(sudo:session): session closed for user root May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:48:48 volumio2 volumio[751]: info: MPD Permissions set May 15 23:48:48 volumio2 systemd[1]: Stopping Music Player Daemon... May 15 23:48:48 volumio2 systemd[1]: mpd.service: Succeeded. May 15 23:48:48 volumio2 systemd[1]: Stopped Music Player Daemon. May 15 23:48:48 volumio2 systemd[1]: Starting Music Player Daemon... May 15 23:48:49 volumio2 volumio[751]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... May 15 23:48:49 volumio2 volumio[751]: info: Setting up python3-asn1crypto (0.24.0-1) ... May 15 23:48:49 volumio2 volumio[751]: error: Upnp client error: Error: This socket has been ended by the other party May 15 23:48:50 volumio2 mpd[4689]: May 15 23:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:48:50 volumio2 systemd[1]: Started Music Player Daemon. May 15 23:48:50 volumio2 sudo[4679]: pam_unix(sudo:session): session closed for user root May 15 23:48:50 volumio2 volumio[751]: error: updateQueue error: null May 15 23:48:50 volumio2 volumio[751]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... May 15 23:48:50 volumio2 volumio[751]: info: Setting up python3-cffi-backend (1.12.2-1) ... May 15 23:48:50 volumio2 volumio[751]: info: Setting up libalgorithm-merge-perl (0.08-3) ... May 15 23:48:50 volumio2 volumio[751]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... May 15 23:48:50 volumio2 volumio[751]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... May 15 23:48:52 volumio2 volumio[751]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... May 15 23:48:52 volumio2 volumio[751]: info: Setting up dpkg-dev (1.19.8) ... May 15 23:48:52 volumio2 volumio[751]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... May 15 23:48:52 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:48:52 volumio2 volumio[751]: info: Listing playlists May 15 23:48:52 volumio2 volumio[751]: info: Listing playlists May 15 23:48:54 volumio2 volumio[751]: info: Setting up python3-pip (18.1-5+rpt1) ... May 15 23:48:55 volumio2 volumio[751]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... May 15 23:48:55 volumio2 volumio[751]: info: Setting up cpp (4:8.3.0-1+rpi2) ... May 15 23:48:55 volumio2 volumio[751]: info: Setting up python3-keyrings.alt (3.1.1-1) ... May 15 23:48:56 volumio2 volumio[751]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... May 15 23:48:56 volumio2 volumio[751]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... May 15 23:48:56 volumio2 volumio[751]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... May 15 23:48:56 volumio2 volumio[751]: info: Setting up gcc (4:8.3.0-1+rpi2) ... May 15 23:48:56 volumio2 volumio[751]: info: Setting up python3-secretstorage (2.3.1-2) ... May 15 23:48:56 volumio2 volumio[751]: info: CoreCommandRouter::volumioStop May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::stop May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::stPlaybackTimer May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::updateTrackBlock May 15 23:48:56 volumio2 volumio[751]: info: CorePlayQueue::getTrackBlock May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:48:56 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:56 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:48:56 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:48:56 volumio2 volumio[751]: info: CoreStateMachine::serviceStop May 15 23:48:56 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:56 volumio2 volumio[751]: info: CoreCommandRouter::serviceStop May 15 23:48:56 volumio2 volumio[751]: info: [1715788136958] ControllerWebradio::stop May 15 23:48:56 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand stop May 15 23:48:56 volumio2 volumio[751]: info: sendMpdCommand stop took 11 milliseconds May 15 23:48:57 volumio2 volumio[751]: info: CoreCommandRouter::volumioPlay May 15 23:48:57 volumio2 volumio[751]: info: CoreStateMachine::play index undefined May 15 23:48:57 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 23:48:57 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:57 volumio2 volumio[751]: info: CoreStateMachine::startPlaybackTimer May 15 23:48:57 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:48:57 volumio2 volumio[751]: info: [1715788137563] ControllerWebradio::clearAddPlayTrack May 15 23:48:57 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand stop May 15 23:48:57 volumio2 volumio[751]: info: sendMpdCommand stop took 3 milliseconds May 15 23:48:57 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand clear May 15 23:48:57 volumio2 volumio[751]: info: May 15 23:48:57 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:48:57 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:48:57 volumio2 volumio[751]: info: sendMpdCommand clear took 2 milliseconds May 15 23:48:57 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand load "https://mediaprima.rastream.com/mediaprima-onefm" May 15 23:48:57 volumio2 volumio[751]: error: updateQueue error: null May 15 23:48:57 volumio2 volumio[751]: info: ------------------------------ 9ms May 15 23:48:57 volumio2 volumio[751]: info: Setting up python3-dev (3.7.3-1) ... May 15 23:48:57 volumio2 volumio[751]: info: Setting up g++-8 (8.3.0-6+rpi1) ... May 15 23:48:57 volumio2 volumio[751]: info: Setting up python3-keyring (17.1.1-1) ... May 15 23:48:58 volumio2 volumio[751]: info: Setting up g++ (4:8.3.0-1+rpi2) ... May 15 23:48:59 volumio2 volumio[751]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode May 15 23:48:59 volumio2 volumio[751]: info: Setting up build-essential (12.6) ... May 15 23:48:59 volumio2 volumio[751]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... May 15 23:49:01 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand add "https://mediaprima.rastream.com/mediaprima-onefm" May 15 23:49:01 volumio2 volumio[751]: Upnp client error: Error: This socket has been ended by the other party May 15 23:49:01 volumio2 volumio[751]: info: May 15 23:49:01 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:49:01 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:49:01 volumio2 volumio[751]: info: sendMpdCommand add "https://mediaprima.rastream.com/mediaprima-onefm" took 6 milliseconds May 15 23:49:01 volumio2 volumio[751]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 23:49:01 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand play May 15 23:49:01 volumio2 volumio[751]: info: ------------------------------ 6ms May 15 23:49:01 volumio2 volumio[751]: info: sendMpdCommand play took 5 milliseconds May 15 23:49:02 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:06 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:07 volumio2 volumio[751]: info: May 15 23:49:07 volumio2 volumio[751]: ---------------------------- MPD announces state update: player May 15 23:49:07 volumio2 volumio[751]: info: ControllerMpd::getState May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand status May 15 23:49:07 volumio2 volumio[751]: info: May 15 23:49:07 volumio2 volumio[751]: ---------------------------- MPD announces system playlist update May 15 23:49:07 volumio2 volumio[751]: info: Ignoring MPD Status Update May 15 23:49:07 volumio2 volumio[751]: info: May 15 23:49:07 volumio2 volumio[751]: ---------------------------- MPD announces state update: player May 15 23:49:07 volumio2 volumio[751]: info: sendMpdCommand status took 58 milliseconds May 15 23:49:07 volumio2 volumio[751]: info: ControllerMpd::getState May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand status May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::parseState May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 23:49:07 volumio2 volumio[751]: info: ------------------------------ 5ms May 15 23:49:07 volumio2 volumio[751]: info: sendMpdCommand status took 3 milliseconds May 15 23:49:07 volumio2 volumio[751]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::parseState May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::parseTrackInfo May 15 23:49:07 volumio2 volumio[751]: info: ControllerMpd::pushState May 15 23:49:07 volumio2 volumio[751]: info: CoreCommandRouter::servicePushState May 15 23:49:07 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:49:07 volumio2 volumio[751]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"GIGI炎明熹（YAN MING XI） - CRYSTAL CLEAR - 电视剧《新闻女王》片尾曲","artist":null,"album":null,"uri":"https://mediaprima.rastream.com/mediaprima-onefm","trackType":""} May 15 23:49:07 volumio2 volumio[751]: verbose: CURRENT POSITION 0 May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::syncState stateService play May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::syncState currentStatus stop May 15 23:49:07 volumio2 volumio[751]: info: ------------------------------ 67ms May 15 23:49:07 volumio2 volumio[751]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 23:49:07 volumio2 volumio[751]: verbose: ControllerMpd::parseTrackInfo May 15 23:49:07 volumio2 volumio[751]: info: ControllerMpd::pushState May 15 23:49:07 volumio2 volumio[751]: info: CoreCommandRouter::servicePushState May 15 23:49:07 volumio2 volumio[751]: info: CorePlayQueue::getTrack 0 May 15 23:49:07 volumio2 volumio[751]: verbose: STATE SERVICE {"status":"play","position":0,"seek":583,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"47 Kbps","isStreaming":false,"title":"GIGI炎明熹（YAN MING XI） - CRYSTAL CLEAR - 电视剧《新闻女王》片尾曲","artist":null,"album":null,"uri":"https://mediaprima.rastream.com/mediaprima-onefm","trackType":""} May 15 23:49:07 volumio2 volumio[751]: verbose: CURRENT POSITION 0 May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::syncState stateService play May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::syncState currentStatus play May 15 23:49:07 volumio2 volumio[751]: info: Received an update from plugin. extracting info from payload May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:49:07 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:49:07 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:49:07 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:49:07 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:49:07 volumio2 volumio[751]: info: ------------------------------ 50ms May 15 23:49:07 volumio2 sudo[2899]: pam_unix(sudo:session): session closed for user root May 15 23:49:07 volumio2 sudo[4830]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 15 23:49:07 volumio2 sudo[4830]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:07 volumio2 volumio[751]: info: lib/systemd/system/fusiondsp.service May 15 23:49:07 volumio2 sudo[4830]: pam_unix(sudo:session): session closed for user root May 15 23:49:10 volumio2 volumio[751]: info: VolumeController::SetAlsaVolume84 May 15 23:49:10 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:49:10 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:49:10 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:49:12 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:12 volumio2 volumio[751]: info: Listing playlists May 15 23:49:12 volumio2 volumio[751]: info: Listing playlists May 15 23:49:22 volumio2 volumio[751]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 15 23:49:22 volumio2 volumio[751]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 15 23:49:22 volumio2 sudo[4866]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 15 23:49:22 volumio2 sudo[4866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:22 volumio2 volumio[751]: info: May 15 23:49:22 volumio2 volumio[751]: info: cgui-1.0.0.zip opened May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/ May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/LICENSE.txt May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/README.md May 15 23:49:22 volumio2 sudo[4866]: pam_unix(sudo:session): session closed for user root May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/build/ May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/robots.txt May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/logo512.png May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/asset-manifest.json May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/index.html May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/.put_statics_here May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/favicon.ico May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/logo192.png May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 15 23:49:22 volumio2 sudo[4868]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 15 23:49:22 volumio2 sudo[4868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/manifest.json May 15 23:49:22 volumio2 sudo[4868]: pam_unix(sudo:session): session closed for user root May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/css-variables.css May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/service-worker.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/build/static/ May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/build/static/css/ May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 15 23:49:22 volumio2 sudo[4870]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 15 23:49:22 volumio2 sudo[4870]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/build/static/media/ May 15 23:49:22 volumio2 sudo[4870]: pam_unix(sudo:session): session closed for user root May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/build/static/js/ May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.71e11955.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 15 23:49:22 volumio2 volumio[751]: info: creating directory: cgui/config/ May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/config/camillagui.yml May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 15 23:49:22 volumio2 volumio[751]: info: extracting: cgui/config/gui-config.yml May 15 23:49:23 volumio2 volumio[751]: info: creating directory: cgui/backend/ May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/filters_test.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/version.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/filters.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/settings.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/views.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/filterdefaults.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__init__.py May 15 23:49:23 volumio2 volumio[751]: info: creating directory: cgui/backend/__pycache__/ May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/routes.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/backend/filemanagement.py May 15 23:49:23 volumio2 volumio[751]: info: extracting: cgui/main.py May 15 23:49:23 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:24 volumio2 ntpd[576]: Soliciting pool server 2001:e68:58bc:b200::123 May 15 23:49:29 volumio2 volumio[751]: info: VolumeController::SetAlsaVolume72 May 15 23:49:29 volumio2 volumio[751]: info: CoreStateMachine::pushState May 15 23:49:29 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 23:49:29 volumio2 volumio[751]: info: CoreCommandRouter::volumioPushState May 15 23:49:32 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:32 volumio2 volumio[751]: info: Listing playlists May 15 23:49:32 volumio2 volumio[751]: info: Listing playlists May 15 23:49:32 volumio2 volumio[751]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple May 15 23:49:32 volumio2 volumio[751]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 May 15 23:49:32 volumio2 volumio[751]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-jt3ygylh May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 23:49:37 volumio2 volumio[751]: info: Getting Alsa Cards List without I2S DAC May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 23:49:37 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 23:49:37 volumio2 volumio[751]: info: Collecting PyYAML (from camilladsp==1.0.0) May 15 23:49:42 volumio2 volumio[751]: info: Downloading https://www.piwheels.org/simple/pyyaml/PyYAML-6.0.1-cp37-cp37m-linux_armv7l.whl (45kB) May 15 23:49:42 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:43 volumio2 volumio[751]: info: Collecting websocket_client (from camilladsp==1.0.0) May 15 23:49:44 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:44 volumio2 volumio[751]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) May 15 23:49:44 volumio2 volumio[751]: info: Building wheels for collected packages: camilladsp May 15 23:49:44 volumio2 volumio[751]: info: Running setup.py bdist_wheel for camilladsp: started May 15 23:49:46 volumio2 volumio[751]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' May 15 23:49:46 volumio2 volumio[751]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-jq8ixlkb/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 May 15 23:49:46 volumio2 volumio[751]: info: Successfully built camilladsp May 15 23:49:47 volumio2 volumio[751]: info: Installing collected packages: PyYAML, websocket-client, camilladsp May 15 23:49:47 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 23:49:47 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 15 23:49:47 volumio2 volumio[751]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 May 15 23:49:51 volumio2 volumio[751]: info: Enabling plugin fusiondsp May 15 23:49:51 volumio2 volumio[751]: info: Loading plugin "fusiondsp"... May 15 23:49:53 volumio2 volumio[751]: info: Preparing to generate the ALSA configuration file May 15 23:49:53 volumio2 volumio[751]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 15 23:49:53 volumio2 volumio[751]: info: Reading ALSA contributions from plugins. May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::volumioGetState May 15 23:49:53 volumio2 volumio[751]: info: Asound.conf file unchanged, so no further update is needed May 15 23:49:53 volumio2 volumio[751]: info: Output device has changed, restarting MPD May 15 23:49:53 volumio2 sudo[5019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:49:53 volumio2 sudo[5019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:53 volumio2 volumio[751]: info: Output device has changed, restarting Shairport Sync May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:53 volumio2 sudo[5019]: pam_unix(sudo:session): session closed for user root May 15 23:49:53 volumio2 sudo[5021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:49:53 volumio2 sudo[5021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:53 volumio2 volumio[751]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:49:53 volumio2 volumio[751]: info: PLUGIN START: fusiondsp May 15 23:49:53 volumio2 volumio[751]: info: Loading i18n strings for locale en May 15 23:49:53 volumio2 systemd[1]: Stopping Music Player Daemon... May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: FusionDsp - mixtype--------------------- Software May 15 23:49:53 volumio2 volumio[751]: info: Preparing to generate the ALSA configuration file May 15 23:49:53 volumio2 volumio[751]: info: Done. May 15 23:49:53 volumio2 volumio[751]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 15 23:49:53 volumio2 volumio[751]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 15 23:49:53 volumio2 volumio[751]: info: Reading ALSA contributions from plugins. May 15 23:49:53 volumio2 volumio[751]: info: MPD Permissions set May 15 23:49:53 volumio2 volumio[751]: info: Listing playlists May 15 23:49:53 volumio2 volumio[751]: info: Listing playlists May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:53 volumio2 volumio[751]: info: Starting Shairport Sync May 15 23:49:53 volumio2 sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:49:53 volumio2 sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:53 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 23:49:53 volumio2 volumio[751]: info: Asound.conf file written May 15 23:49:54 volumio2 sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 15 23:49:54 volumio2 sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:54 volumio2 sudo[5035]: pam_unix(sudo:session): session closed for user root May 15 23:49:54 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 15 23:49:54 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 23:49:54 volumio2 volumio[751]: info: Output device has changed, restarting MPD May 15 23:49:54 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 23:49:54 volumio2 sudo[5032]: pam_unix(sudo:session): session closed for user root May 15 23:49:54 volumio2 volumio[751]: info: Output device has changed, restarting Shairport Sync May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:54 volumio2 sudo[5041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 23:49:54 volumio2 sudo[5041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:54 volumio2 sudo[5043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 23:49:54 volumio2 sudo[5043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:54 volumio2 sudo[5041]: pam_unix(sudo:session): session closed for user root May 15 23:49:54 volumio2 volumio[751]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 23:49:54 volumio2 volumio[751]: info: MPD Permissions set May 15 23:49:54 volumio2 volumio[751]: info: Shairport-Sync Started May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 23:49:54 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:54 volumio2 volumio[751]: error: Upnp client error: Error: This socket has been ended by the other party May 15 23:49:54 volumio2 volumio[751]: info: Starting Shairport Sync May 15 23:49:54 volumio2 sudo[5054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 23:49:54 volumio2 sudo[5054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:54 volumio2 systemd[1]: mpd.service: Succeeded. May 15 23:49:54 volumio2 systemd[1]: Stopped Music Player Daemon. May 15 23:49:54 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 23:49:54 volumio2 systemd[1]: Starting Music Player Daemon... May 15 23:49:54 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 15 23:49:54 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 15 23:49:54 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 23:49:54 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 23:49:54 volumio2 sudo[5054]: pam_unix(sudo:session): session closed for user root May 15 23:49:54 volumio2 volumio[751]: info: Shairport-Sync Started May 15 23:49:55 volumio2 volumio[751]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 15 23:49:55 volumio2 volumio[751]: info: camilladsp service started and running in background, instance 1 May 15 23:49:55 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 23:49:55 volumio2 volumio[751]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 15 23:49:55 volumio2 volumio[751]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 15 23:49:55 volumio2 volumio[751]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 15 23:49:55 volumio2 volumio[751]: info: FusionDsp loaded May 15 23:49:55 volumio2 volumio[751]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 23:49:55 volumio2 sudo[5073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 15 23:49:55 volumio2 sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 23:49:55 volumio2 volumio[751]: info: FusionDsp - Reporting Fusion DSP Enabled May 15 23:49:55 volumio2 volumio[751]: info: Adding Signal Path Element [object Object] May 15 23:49:55 volumio2 volumio[751]: info: Adding fusiondspeq DSP Signal Path Element May 15 23:49:55 volumio2 volumio[751]: info: FusionDsp - ---- installed callbackRead May 15 23:49:55 volumio2 volumio[751]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:49:55 volumio2 systemd[1]: Started FusionDsp Daemon. May 15 23:49:55 volumio2 volumio[751]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 15 23:49:55 volumio2 volumio[751]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 15 23:49:55 volumio2 volumio[751]: at onErrorNT (internal/child_process.js:465:16) May 15 23:49:55 volumio2 volumio[751]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 15 23:49:55 volumio2 volumio[751]: errno: -2, May 15 23:49:55 volumio2 volumio[751]: code: 'ENOENT', May 15 23:49:55 volumio2 volumio[751]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 15 23:49:55 volumio2 volumio[751]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 15 23:49:55 volumio2 volumio[751]: spawnargs: [ May 15 23:49:55 volumio2 volumio[751]: '-p', May 15 23:49:55 volumio2 volumio[751]: 9876, May 15 23:49:55 volumio2 volumio[751]: '-o', May 15 23:49:55 volumio2 volumio[751]: '/tmp/camilladsp.log', May 15 23:49:55 volumio2 volumio[751]: '-l', May 15 23:49:55 volumio2 volumio[751]: 'warn', May 15 23:49:55 volumio2 volumio[751]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 15 23:49:55 volumio2 volumio[751]: ] May 15 23:49:55 volumio2 volumio[751]: } May 15 23:49:55 volumio2 volumio[751]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:49:55 volumio2 sudo[5073]: pam_unix(sudo:session): session closed for user root May 15 23:49:59 volumio2 mpd[5056]: May 15 23:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 23:49:59 volumio2 systemd[1]: Started Music Player Daemon. May 15 23:49:59 volumio2 sudo[5021]: pam_unix(sudo:session): session closed for user root May 15 23:49:59 volumio2 sudo[5043]: pam_unix(sudo:session): session closed for user root May 15 23:50:00 volumio2 sudo[5095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 23:48 May 15 23:50:00 volumio2 sudo[5095]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"