-- Logs begin at Wed 2025-09-10 21:42:22 JST, end at Thu 2025-09-11 17:24:25 JST. -- Sep 11 17:23:00 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 11 17:23:00 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:00 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:00 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:00 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:00 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:00 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 11 17:23:00 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:00 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Sep 11 17:23:00 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand update Sep 11 17:23:00 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:00 volumio volumio[964]: info: Sep 11 17:23:00 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:00 volumio volumio[964]: info: sendMpdCommand update took 44 milliseconds Sep 11 17:23:00 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:00 volumio volumio[964]: info: Sep 11 17:23:00 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:00 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:00 volumio volumio[964]: info: sendMpdCommand status took 17 milliseconds Sep 11 17:23:00 volumio volumio[964]: info: sendMpdCommand status took 13 milliseconds Sep 11 17:23:00 volumio volumio[964]: info: sendMpdCommand status took 11 milliseconds Sep 11 17:23:00 volumio volumio[964]: info: sendMpdCommand status took 6 milliseconds Sep 11 17:23:00 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:02 volumio go-librespot[1225]: time="2025-09-11T17:23:02+09:00" level=trace msg="sent dealer ping" Sep 11 17:23:02 volumio go-librespot[1225]: time="2025-09-11T17:23:02+09:00" level=trace msg="received dealer pong" Sep 11 17:23:03 volumio volumio[964]: info: Command Router : Notfying DB Updatetrue Sep 11 17:23:03 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:03 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:03 volumio volumio[964]: info: Command Router : Notfying DB Updatetrue Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:03 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:03 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:03 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:03 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:03 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:03 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:03 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:03 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:03 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:03 volumio volumio[964]: info: ------------------------------ 3166ms Sep 11 17:23:03 volumio volumio[964]: info: ------------------------------ 3162ms Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:23:03 volumio volumio[964]: info: CoreStateMachine::getQueue Sep 11 17:23:03 volumio volumio[964]: info: CorePlayQueue::getQueue Sep 11 17:23:03 volumio volumio[964]: info: Listing playlists Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 17:23:03 volumio volumio[964]: info: Received Get System Info Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 17:23:03 volumio volumio[964]: info: Discovery: Getting this device information Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 17:23:03 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:04 volumio volumio[964]: info: MPD Database updated - AlbumList cache refreshed Sep 11 17:23:04 volumio volumio[964]: info: Sep 11 17:23:04 volumio volumio[964]: ---------------------------- MPD announces state update: database Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: info: Sep 11 17:23:04 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: info: MPD Database updated - AlbumList cache refreshed Sep 11 17:23:04 volumio volumio[964]: info: Sep 11 17:23:04 volumio volumio[964]: ---------------------------- MPD announces state update: database Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: info: Sep 11 17:23:04 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:04 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 89 milliseconds Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 88 milliseconds Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 88 milliseconds Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 37 milliseconds Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 36 milliseconds Sep 11 17:23:04 volumio volumio[964]: info: sendMpdCommand status took 36 milliseconds Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: info: Command Router : Notfying DB Updatefalse Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: info: Command Router : Notfying DB Updatefalse Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:04 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:04 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:04 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:04 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:04 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:04 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:04 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:04 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:04 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:04 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:04 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:04 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:04 volumio volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Sep 11 17:23:04 volumio volumio[964]: info: ------------------------------ 223ms Sep 11 17:23:04 volumio volumio[964]: info: ------------------------------ 223ms Sep 11 17:23:04 volumio volumio[964]: info: ------------------------------ 170ms Sep 11 17:23:04 volumio volumio[964]: info: ------------------------------ 170ms Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:04 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:04 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:23:04 volumio volumio[964]: info: FusionDsp - Choose a preset Sep 11 17:23:04 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:23:19 volumio volumio[964]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::ClearQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::serviceStop Sep 11 17:23:19 volumio volumio[964]: info: Received STOP, but no service to execute it Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::clearPlayQueue Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::addQueueItems Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::addQueueItems Sep 11 17:23:19 volumio volumio[964]: info: Preload queue cleared Sep 11 17:23:19 volumio volumio[964]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/undefined Sep 11 17:23:19 volumio volumio[964]: info: Exploding uri INTERNAL/FusionDsp/tools/undefined in service mpd Sep 11 17:23:19 volumio volumio[964]: error: scanFolder - failure to stat '/mnt/INTERNAL/FusionDsp/tools/undefined' Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioClearQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::ClearQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::serviceStop Sep 11 17:23:19 volumio volumio[964]: info: Received STOP, but no service to execute it Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::clearPlayQueue Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::updateTrackBlock Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::getTrackBlock Sep 11 17:23:19 volumio volumio[964]: info: CoreCommandRouter::volumioPlay Sep 11 17:23:19 volumio volumio[964]: verbose: UNSET VOLATILE: Service: undefined Sep 11 17:23:19 volumio volumio[964]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::play index 0 Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::play index undefined Sep 11 17:23:19 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:19 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:19 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:19 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:19 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:19 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:19 volumio sudo[26493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 25183 Sep 11 17:23:19 volumio sudo[26493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:23:19 volumio sudo[26493]: pam_unix(sudo:session): session closed for user root Sep 11 17:23:19 volumio volumio[964]: info: Shairport-Sync paused with USR2 Sep 11 17:23:20 volumio volumio[964]: info: Restarting Shairport-Sync after stop Sep 11 17:23:20 volumio sudo[26496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 11 17:23:20 volumio sudo[26496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:23:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 11 17:23:20 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 11 17:23:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 11 17:23:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 11 17:23:21 volumio sudo[26496]: pam_unix(sudo:session): session closed for user root Sep 11 17:23:21 volumio volumio[964]: info: Shairport-Sync retarted Sep 11 17:23:24 volumio volumio[964]: info: CALLMETHOD: audio_interface fusiondsp installtools Sep 11 17:23:24 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , installtools Sep 11 17:23:30 volumio volumio-remote-updater[642]: [2025-09-11 17:23:30] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 11 17:23:30 volumio volumio[964]: --2025-09-11 17:23:24-- https://github.com/balbuze/volumio-plugins/raw/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Sep 11 17:23:30 volumio volumio[964]: Resolving github.com (github.com)... 20.27.177.113 Sep 11 17:23:30 volumio volumio[964]: Connecting to github.com (github.com)|20.27.177.113|:443... connected. Sep 11 17:23:30 volumio volumio[964]: HTTP request sent, awaiting response... 302 Found Sep 11 17:23:30 volumio volumio[964]: Location: https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz [following] Sep 11 17:23:30 volumio volumio[964]: --2025-09-11 17:23:25-- https://raw.githubusercontent.com/balbuze/volumio-plugins/alsa_modular/plugins/audio_interface/FusionDsp/tools/tools.tar.xz Sep 11 17:23:30 volumio volumio[964]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.111.133, 185.199.108.133, ... Sep 11 17:23:30 volumio volumio[964]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. Sep 11 17:23:30 volumio volumio[964]: HTTP request sent, awaiting response... 200 OK Sep 11 17:23:30 volumio volumio[964]: Length: 23035700 (22M) [application/octet-stream] Sep 11 17:23:30 volumio volumio[964]: Saving to: ‘/tmp/tools.tar.xz’ Sep 11 17:23:30 volumio volumio[964]: 0K .......... .......... .......... .......... .......... 0% 2.03M 11s Sep 11 17:23:30 volumio volumio[964]: 50K .......... .......... .......... .......... .......... 0% 2.19M 10s Sep 11 17:23:30 volumio volumio[964]: 100K .......... .......... .......... .......... .......... 0% 6.81M 8s Sep 11 17:23:30 volumio volumio[964]: 150K .......... .......... .......... .......... .......... 0% 6.21M 7s Sep 11 17:23:30 volumio volumio[964]: 200K .......... .......... .......... .......... .......... 1% 3.49M 7s Sep 11 17:23:30 volumio volumio[964]: 250K .......... .......... .......... .......... .......... 1% 4.39M 6s Sep 11 17:23:30 volumio volumio[964]: 300K .......... .......... .......... .......... .......... 1% 6.30M 6s Sep 11 17:23:30 volumio volumio[964]: 350K .......... .......... .......... .......... .......... 1% 3.80M 6s Sep 11 17:23:30 volumio volumio[964]: 400K .......... .......... .......... .......... .......... 2% 4.87M 6s Sep 11 17:23:30 volumio volumio[964]: 450K .......... .......... .......... .......... .......... 2% 3.98M 6s Sep 11 17:23:30 volumio volumio[964]: 500K .......... .......... .......... .......... .......... 2% 4.28M 6s Sep 11 17:23:30 volumio volumio[964]: 550K .......... .......... .......... .......... .......... 2% 2.96M 6s Sep 11 17:23:30 volumio volumio[964]: 600K .......... .......... .......... .......... .......... 2% 4.45M 6s Sep 11 17:23:30 volumio volumio[964]: 650K .......... .......... .......... .......... .......... 3% 2.84M 6s Sep 11 17:23:30 volumio volumio[964]: 700K .......... .......... .......... .......... .......... 3% 7.14M 6s Sep 11 17:23:30 volumio volumio[964]: 750K .......... .......... .......... .......... .......... 3% 4.87M 5s Sep 11 17:23:30 volumio volumio[964]: 800K .......... .......... .......... .......... .......... 3% 6.14M 5s Sep 11 17:23:30 volumio volumio[964]: 850K .......... .......... .......... .......... .......... 4% 6.23M 5s Sep 11 17:23:30 volumio volumio[964]: 900K .......... .......... .......... .......... .......... 4% 7.82M 5s Sep 11 17:23:30 volumio volumio[964]: 950K .......... .......... .......... .......... .......... 4% 2.42M 5s Sep 11 17:23:30 volumio volumio[964]: 1000K .......... .......... .......... .......... .......... 4% 6.12M 5s Sep 11 17:23:30 volumio volumio[964]: 1050K .......... .......... .......... .......... .......... 4% 5.34M 5s Sep 11 17:23:30 volumio volumio[964]: 1100K .......... .......... .......... .......... .......... 5% 5.62M 5s Sep 11 17:23:30 volumio volumio[964]: 1150K .......... .......... .......... .......... .......... 5% 4.99M 5s Sep 11 17:23:30 volumio volumio[964]: 1200K .......... .......... .......... .......... .......... 5% 5.85M 5s Sep 11 17:23:30 volumio volumio[964]: 1250K .......... .......... .......... .......... .......... 5% 5.00M 5s Sep 11 17:23:30 volumio volumio[964]: 1300K .......... .......... .......... .......... .......... 6% 5.16M 5s Sep 11 17:23:30 volumio volumio[964]: 1350K .......... .......... .......... .......... .......... 6% 4.08M 5s Sep 11 17:23:30 volumio volumio[964]: 1400K .......... .......... .......... .......... .......... 6% 3.56M 5s Sep 11 17:23:30 volumio volumio[964]: 1450K .......... .......... .......... .......... .......... 6% 4.45M 5s Sep 11 17:23:30 volumio volumio[964]: 1500K .......... .......... .......... .......... .......... 6% 6.05M 5s Sep 11 17:23:30 volumio volumio[964]: 1550K .......... .......... .......... .......... .......... 7% 4.40M 5s Sep 11 17:23:30 volumio volumio[964]: 1600K .......... .......... .......... .......... .......... 7% 4.64M 5s Sep 11 17:23:30 volumio volumio[964]: 1650K .......... .......... .......... .......... .......... 7% 4.82M 5s Sep 11 17:23:30 volumio volumio[964]: 1700K .......... .......... .......... .......... .......... 7% 7.00M 5s Sep 11 17:23:30 volumio volumio[964]: 1750K .......... .......... .......... .......... .......... 8% 3.98M 5s Sep 11 17:23:30 volumio volumio[964]: 1800K .......... .......... .......... .......... .......... 8% 4.81M 5s Sep 11 17:23:30 volumio volumio[964]: 1850K .......... .......... .......... .......... .......... 8% 7.31M 5s Sep 11 17:23:30 volumio volumio[964]: 1900K .......... .......... .......... .......... .......... 8% 5.10M 5s Sep 11 17:23:30 volumio volumio[964]: 1950K .......... .......... .......... .......... .......... 8% 2.37M 5s Sep 11 17:23:30 volumio volumio[964]: 2000K .......... .......... .......... .......... .......... 9% 6.14M 5s Sep 11 17:23:30 volumio volumio[964]: 2050K .......... .......... .......... .......... .......... 9% 6.21M 5s Sep 11 17:23:30 volumio volumio[964]: 2100K .......... .......... .......... .......... .......... 9% 5.34M 4s Sep 11 17:23:30 volumio volumio[964]: 2150K .......... .......... .......... .......... .......... 9% 3.93M 4s Sep 11 17:23:30 volumio volumio[964]: 2200K .......... .......... .......... .......... .......... 10% 6.44M 4s Sep 11 17:23:30 volumio volumio[964]: 2250K .......... .......... .......... .......... .......... 10% 2.75M 5s Sep 11 17:23:30 volumio volumio[964]: 2300K .......... .......... .......... .......... .......... 10% 4.81M 4s Sep 11 17:23:30 volumio volumio[964]: 2350K .......... .......... .......... .......... .......... 10% 2.21M 5s Sep 11 17:23:30 volumio volumio[964]: 2400K .......... .......... .......... .......... .......... 10% 4.63M 5s Sep 11 17:23:30 volumio volumio[964]: 2450K .......... .......... .......... .......... .......... 11% 7.46M 4s Sep 11 17:23:30 volumio volumio[964]: 2500K .......... .......... .......... .......... .......... 11% 5.59M 4s Sep 11 17:23:30 volumio volumio[964]: 2550K .......... .......... .......... .......... .......... 11% 4.86M 4s Sep 11 17:23:30 volumio volumio[964]: 2600K .......... .......... .......... .......... .......... 11% 4.89M 4s Sep 11 17:23:30 volumio volumio[964]: 2650K .......... .......... .......... .......... .......... 12% 7.07M 4s Sep 11 17:23:30 volumio volumio[964]: 2700K .......... .......... .......... .......... .......... 12% 4.49M 4s Sep 11 17:23:30 volumio volumio[964]: 2750K .......... .......... .......... .......... .......... 12% 4.36M 4s Sep 11 17:23:30 volumio volumio[964]: 2800K .......... .......... .......... .......... .......... 12% 5.47M 4s Sep 11 17:23:30 volumio volumio[964]: 2850K .......... .......... .......... .......... .......... 12% 3.71M 4s Sep 11 17:23:30 volumio volumio[964]: 2900K .......... .......... .......... .......... .......... 13% 4.50M 4s Sep 11 17:23:30 volumio volumio[964]: 2950K .......... .......... .......... .......... .......... 13% 6.04M 4s Sep 11 17:23:30 volumio volumio[964]: 3000K .......... .......... .......... .......... .......... 13% 5.45M 4s Sep 11 17:23:30 volumio volumio[964]: 3050K .......... .......... .......... .......... .......... 13% 5.28M 4s Sep 11 17:23:30 volumio volumio[964]: 3100K .......... .......... .......... .......... .......... 14% 4.38M 4s Sep 11 17:23:30 volumio volumio[964]: 3150K .......... .......... .......... .......... .......... 14% 3.59M 4s Sep 11 17:23:30 volumio volumio[964]: 3200K .......... .......... .......... .......... .......... 14% 3.78M 4s Sep 11 17:23:30 volumio volumio[964]: 3250K .......... .......... .......... .......... .......... 14% 5.39M 4s Sep 11 17:23:30 volumio volumio[964]: 3300K .......... .......... .......... .......... .......... 14% 2.72M 4s Sep 11 17:23:30 volumio volumio[964]: 3350K .......... .......... .......... .......... .......... 15% 4.36M 4s Sep 11 17:23:30 volumio volumio[964]: 3400K .......... .......... .......... .......... .......... 15% 6.17M 4s Sep 11 17:23:30 volumio volumio[964]: 3450K .......... .......... .......... .......... .......... 15% 5.38M 4s Sep 11 17:23:30 volumio volumio[964]: 3500K .......... .......... .......... .......... .......... 15% 6.30M 4s Sep 11 17:23:30 volumio volumio[964]: 3550K .......... .......... .......... .......... .......... 16% 3.42M 4s Sep 11 17:23:30 volumio volumio[964]: 3600K .......... .......... .......... .......... .......... 16% 4.52M 4s Sep 11 17:23:30 volumio volumio[964]: 3650K .......... .......... .......... .......... .......... 16% 5.48M 4s Sep 11 17:23:30 volumio volumio[964]: 3700K .......... .......... .......... .......... .......... 16% 4.35M 4s Sep 11 17:23:30 volumio volumio[964]: 3750K .......... .......... .......... .......... .......... 16% 5.42M 4s Sep 11 17:23:30 volumio volumio[964]: 3800K .......... .......... .......... .......... .......... 17% 2.66M 4s Sep 11 17:23:30 volumio volumio[964]: 3850K .......... .......... .......... .......... .......... 17% 6.31M 4s Sep 11 17:23:30 volumio volumio[964]: 3900K .......... .......... .......... .......... .......... 17% 4.60M 4s Sep 11 17:23:30 volumio volumio[964]: 3950K .......... .......... .......... .......... .......... 17% 4.34M 4s Sep 11 17:23:30 volumio volumio[964]: 4000K .......... .......... .......... .......... .......... 18% 3.82M 4s Sep 11 17:23:30 volumio volumio[964]: 4050K .......... .......... .......... .......... .......... 18% 6.85M 4s Sep 11 17:23:30 volumio volumio[964]: 4100K .......... .......... .......... .......... .......... 18% 6.87M 4s Sep 11 17:23:30 volumio volumio[964]: 4150K .......... .......... .......... .......... .......... 18% 6.05M 4s Sep 11 17:23:30 volumio volumio[964]: 4200K .......... .......... .......... .......... .......... 18% 4.98M 4s Sep 11 17:23:30 volumio volumio[964]: 4250K .......... .......... .......... .......... .......... 19% 5.40M 4s Sep 11 17:23:30 volumio volumio[964]: 4300K .......... .......... .......... .......... .......... 19% 2.71M 4s Sep 11 17:23:30 volumio volumio[964]: 4350K .......... .......... .......... .......... .......... 19% 3.09M 4s Sep 11 17:23:30 volumio volumio[964]: 4400K .......... .......... .......... .......... .......... 19% 4.80M 4s Sep 11 17:23:30 volumio volumio[964]: 4450K .......... .......... .......... .......... .......... 20% 5.29M 4s Sep 11 17:23:30 volumio volumio[964]: 4500K .......... .......... .......... .......... .......... 20% 4.57M 4s Sep 11 17:23:30 volumio volumio[964]: 4550K .......... .......... .......... .......... .......... 20% 4.45M 4s Sep 11 17:23:30 volumio volumio[964]: 4600K .......... .......... .......... .......... .......... 20% 4.81M 4s Sep 11 17:23:30 volumio volumio[964]: 4650K .......... .......... .......... .......... .......... 20% 7.09M 4s Sep 11 17:23:30 volumio volumio[964]: 4700K .......... .......... .......... .......... .......... 21% 6.62M 4s Sep 11 17:23:30 volumio volumio[964]: 4750K .......... .......... .......... .......... .......... 21% 2.89M 4s Sep 11 17:23:30 volumio volumio[964]: 4800K .......... .......... .......... .......... .......... 21% 2.62M 4s Sep 11 17:23:30 volumio volumio[964]: 4850K .......... .......... .......... .......... .......... 21% 5.94M 4s Sep 11 17:23:30 volumio volumio[964]: 4900K .......... .......... .......... .......... .......... 22% 4.88M 4s Sep 11 17:23:30 volumio volumio[964]: 4950K .......... .......... .......... .......... .......... 22% 3.22M 4s Sep 11 17:23:30 volumio volumio[964]: 5000K .......... .......... .......... .......... .......... 22% 6.12M 4s Sep 11 17:23:30 volumio volumio[964]: 5050K .......... .......... .......... .......... .......... 22% 4.12M 4s Sep 11 17:23:30 volumio volumio[964]: 5100K .......... .......... .......... .......... .......... 22% 5.33M 4s Sep 11 17:23:30 volumio volumio[964]: 5150K .......... .......... .......... .......... .......... 23% 2.06M 4s Sep 11 17:23:30 volumio volumio[964]: 5200K .......... .......... .......... .......... .......... 23% 4.06M 4s Sep 11 17:23:30 volumio volumio[964]: 5250K .......... .......... .......... .......... .......... 23% 5.53M 4s Sep 11 17:23:30 volumio volumio[964]: 5300K .......... .......... .......... .......... .......... 23% 4.68M 4s Sep 11 17:23:30 volumio volumio[964]: 5350K .......... .......... .......... .......... .......... 24% 5.07M 4s Sep 11 17:23:30 volumio volumio[964]: 5400K .......... .......... .......... .......... .......... 24% 4.45M 4s Sep 11 17:23:30 volumio volumio[964]: 5450K .......... .......... .......... .......... .......... 24% 6.62M 4s Sep 11 17:23:30 volumio volumio[964]: 5500K .......... .......... .......... .......... .......... 24% 3.80M 4s Sep 11 17:23:30 volumio volumio[964]: 5550K .......... .......... .......... .......... .......... 24% 4.45M 4s Sep 11 17:23:30 volumio volumio[964]: 5600K .......... .......... .......... .......... .......... 25% 4.92M 4s Sep 11 17:23:30 volumio volumio[964]: 5650K .......... .......... .......... .......... .......... 25% 3.02M 4s Sep 11 17:23:30 volumio volumio[964]: 5700K .......... .......... .......... .......... .......... 25% 5.56M 4s Sep 11 17:23:30 volumio volumio[964]: 5750K .......... .......... .......... .......... .......... 25% 4.36M 4s Sep 11 17:23:30 volumio volumio[964]: 5800K .......... .......... .......... .......... .......... 26% 5.40M 4s Sep 11 17:23:30 volumio volumio[964]: 5850K .......... .......... .......... .......... .......... 26% 4.49M 4s Sep 11 17:23:30 volumio volumio[964]: 5900K .......... .......... .......... .......... .......... 26% 5.96M 4s Sep 11 17:23:30 volumio volumio[964]: 5950K .......... .......... .......... .......... .......... 26% 4.99M 4s Sep 11 17:23:30 volumio volumio[964]: 6000K .......... .......... .......... .......... .......... 26% 5.89M 4s Sep 11 17:23:30 volumio volumio[964]: 6050K .......... .......... .......... .......... .......... 27% 6.26M 4s Sep 11 17:23:30 volumio volumio[964]: 6100K .......... .......... .......... .......... .......... 27% 2.21M 4s Sep 11 17:23:30 volumio volumio[964]: 6150K .......... .......... .......... .......... .......... 27% 5.48M 4s Sep 11 17:23:30 volumio volumio[964]: 6200K .......... .......... .......... .......... .......... 27% 3.77M 4s Sep 11 17:23:30 volumio volumio[964]: 6250K .......... .......... .......... .......... .......... 28% 6.02M 4s Sep 11 17:23:30 volumio volumio[964]: 6300K .......... .......... .......... .......... .......... 28% 3.53M 4s Sep 11 17:23:30 volumio volumio[964]: 6350K .......... .......... .......... .......... .......... 28% 2.68M 4s Sep 11 17:23:30 volumio volumio[964]: 6400K .......... .......... .......... .......... .......... 28% 7.33M 4s Sep 11 17:23:30 volumio volumio[964]: 6450K .......... .......... .......... .......... .......... 28% 5.42M 4s Sep 11 17:23:30 volumio volumio[964]: 6500K .......... .......... .......... .......... .......... 29% 2.85M 4s Sep 11 17:23:30 volumio volumio[964]: 6550K .......... .......... .......... .......... .......... 29% 3.67M 4s Sep 11 17:23:30 volumio volumio[964]: 6600K .......... .......... .......... .......... .......... 29% 6.26M 4s Sep 11 17:23:30 volumio volumio[964]: 6650K .......... .......... .......... .......... .......... 29% 3.76M 4s Sep 11 17:23:30 volumio volumio[964]: 6700K .......... .......... .......... .......... .......... 30% 7.02M 3s Sep 11 17:23:30 volumio volumio[964]: 6750K .......... .......... .......... .......... .......... 30% 3.53M 3s Sep 11 17:23:30 volumio volumio[964]: 6800K .......... .......... .......... .......... .......... 30% 4.41M 3s Sep 11 17:23:30 volumio volumio[964]: 6850K .......... .......... .......... .......... .......... 30% 5.37M 3s Sep 11 17:23:30 volumio volumio[964]: 6900K .......... .......... .......... .......... .......... 30% 6.15M 3s Sep 11 17:23:30 volumio volumio[964]: 6950K .......... .......... .......... .......... .......... 31% 6.15M 3s Sep 11 17:23:30 volumio volumio[964]: 7000K .......... .......... .......... .......... .......... 31% 4.80M 3s Sep 11 17:23:30 volumio volumio[964]: 7050K .......... .......... .......... .......... .......... 31% 3.48M 3s Sep 11 17:23:30 volumio volumio[964]: 7100K .......... .......... .......... .......... .......... 31% 5.43M 3s Sep 11 17:23:30 volumio volumio[964]: 7150K .......... .......... .......... .......... .......... 32% 4.16M 3s Sep 11 17:23:30 volumio volumio[964]: 7200K .......... .......... .......... .......... .......... 32% 7.76M 3s Sep 11 17:23:30 volumio volumio[964]: 7250K .......... .......... .......... .......... .......... 32% 3.06M 3s Sep 11 17:23:30 volumio volumio[964]: 7300K .......... .......... .......... .......... .......... 32% 6.86M 3s Sep 11 17:23:30 volumio volumio[964]: 7350K .......... .......... .......... .......... .......... 32% 3.11M 3s Sep 11 17:23:30 volumio volumio[964]: 7400K .......... .......... .......... .......... .......... 33% 5.16M 3s Sep 11 17:23:30 volumio volumio[964]: 7450K .......... .......... .......... .......... .......... 33% 3.79M 3s Sep 11 17:23:30 volumio volumio[964]: 7500K .......... .......... .......... .......... .......... 33% 3.54M 3s Sep 11 17:23:30 volumio volumio[964]: 7550K .......... .......... .......... .......... .......... 33% 4.08M 3s Sep 11 17:23:30 volumio volumio[964]: 7600K .......... .......... .......... .......... .......... 34% 6.20M 3s Sep 11 17:23:30 volumio volumio[964]: 7650K .......... .......... .......... .......... .......... 34% 1.80M 3s Sep 11 17:23:30 volumio volumio[964]: 7700K .......... .......... .......... .......... .......... 34% 6.16M 3s Sep 11 17:23:30 volumio volumio[964]: 7750K .......... .......... .......... .......... .......... 34% 4.02M 3s Sep 11 17:23:30 volumio volumio[964]: 7800K .......... .......... .......... .......... .......... 34% 6.67M 3s Sep 11 17:23:30 volumio volumio[964]: 7850K .......... .......... .......... .......... .......... 35% 6.42M 3s Sep 11 17:23:30 volumio volumio[964]: 7900K .......... .......... .......... .......... .......... 35% 3.47M 3s Sep 11 17:23:30 volumio volumio[964]: 7950K .......... .......... .......... .......... .......... 35% 3.10M 3s Sep 11 17:23:30 volumio volumio[964]: 8000K .......... .......... .......... .......... .......... 35% 5.95M 3s Sep 11 17:23:30 volumio volumio[964]: 8050K .......... .......... .......... .......... .......... 36% 6.03M 3s Sep 11 17:23:30 volumio volumio[964]: 8100K .......... .......... .......... .......... .......... 36% 3.80M 3s Sep 11 17:23:30 volumio volumio[964]: 8150K .......... .......... .......... .......... .......... 36% 5.90M 3s Sep 11 17:23:30 volumio volumio[964]: 8200K .......... .......... .......... .......... .......... 36% 3.32M 3s Sep 11 17:23:30 volumio volumio[964]: 8250K .......... .......... .......... .......... .......... 36% 5.53M 3s Sep 11 17:23:30 volumio volumio[964]: 8300K .......... .......... .......... .......... .......... 37% 2.88M 3s Sep 11 17:23:30 volumio volumio[964]: 8350K .......... .......... .......... .......... .......... 37% 3.43M 3s Sep 11 17:23:30 volumio volumio[964]: 8400K .......... .......... .......... .......... .......... 37% 3.74M 3s Sep 11 17:23:30 volumio volumio[964]: 8450K .......... .......... .......... .......... .......... 37% 3.72M 3s Sep 11 17:23:30 volumio volumio[964]: 8500K .......... .......... .......... .......... .......... 38% 3.48M 3s Sep 11 17:23:30 volumio volumio[964]: 8550K .......... .......... .......... .......... .......... 38% 5.47M 3s Sep 11 17:23:30 volumio volumio[964]: 8600K .......... .......... .......... .......... .......... 38% 5.34M 3s Sep 11 17:23:30 volumio volumio[964]: 8650K .......... .......... .......... .......... .......... 38% 4.12M 3s Sep 11 17:23:30 volumio volumio[964]: 8700K .......... .......... .......... .......... .......... 38% 6.95M 3s Sep 11 17:23:30 volumio volumio[964]: 8750K .......... .......... .......... .......... .......... 39% 3.75M 3s Sep 11 17:23:30 volumio volumio[964]: 8800K .......... .......... .......... .......... .......... 39% 2.30M 3s Sep 11 17:23:30 volumio volumio[964]: 8850K .......... .......... .......... .......... .......... 39% 5.56M 3s Sep 11 17:23:30 volumio volumio[964]: 8900K .......... .......... .......... .......... .......... 39% 5.43M 3s Sep 11 17:23:30 volumio volumio[964]: 8950K .......... .......... .......... .......... .......... 40% 3.72M 3s Sep 11 17:23:30 volumio volumio[964]: 9000K .......... .......... .......... .......... .......... 40% 6.19M 3s Sep 11 17:23:30 volumio volumio[964]: 9050K .......... .......... .......... .......... .......... 40% 4.56M 3s Sep 11 17:23:30 volumio volumio[964]: 9100K .......... .......... .......... .......... .......... 40% 7.80M 3s Sep 11 17:23:30 volumio volumio[964]: 9150K .......... .......... .......... .......... .......... 40% 4.93M 3s Sep 11 17:23:30 volumio volumio[964]: 9200K .......... .......... .......... .......... .......... 41% 7.01M 3s Sep 11 17:23:30 volumio volumio[964]: 9250K .......... .......... .......... .......... .......... 41% 537K 3s Sep 11 17:23:30 volumio volumio[964]: 9300K .......... .......... .......... .......... .......... 41% 5.49M 3s Sep 11 17:23:30 volumio volumio[964]: 9350K .......... .......... .......... .......... .......... 41% 1.88M 3s Sep 11 17:23:30 volumio volumio[964]: 9400K .......... .......... .......... .......... .......... 42% 4.01M 3s Sep 11 17:23:30 volumio volumio[964]: 9450K .......... .......... .......... .......... .......... 42% 5.42M 3s Sep 11 17:23:30 volumio volumio[964]: 9500K .......... .......... .......... .......... .......... 42% 1.97M 3s Sep 11 17:23:30 volumio volumio[964]: 9550K .......... .......... .......... .......... .......... 42% 2.13M 3s Sep 11 17:23:30 volumio volumio[964]: 9600K .......... .......... .......... .......... .......... 42% 5.35M 3s Sep 11 17:23:30 volumio volumio[964]: 9650K .......... .......... .......... .......... .......... 43% 2.24M 3s Sep 11 17:23:30 volumio volumio[964]: 9700K .......... .......... .......... .......... .......... 43% 5.45M 3s Sep 11 17:23:30 volumio volumio[964]: 9750K .......... .......... .......... .......... .......... 43% 4.84M 3s Sep 11 17:23:30 volumio volumio[964]: 9800K .......... .......... .......... .......... .......... 43% 6.76M 3s Sep 11 17:23:30 volumio volumio[964]: 9850K .......... .......... .......... .......... .......... 44% 3.49M 3s Sep 11 17:23:30 volumio volumio[964]: 9900K .......... .......... .......... .......... .......... 44% 4.42M 3s Sep 11 17:23:30 volumio volumio[964]: 9950K .......... .......... .......... .......... .......... 44% 3.14M 3s Sep 11 17:23:30 volumio volumio[964]: 10000K .......... .......... .......... .......... .......... 44% 4.68M 3s Sep 11 17:23:30 volumio volumio[964]: 10050K .......... .......... .......... .......... .......... 44% 3.09M 3s Sep 11 17:23:30 volumio volumio[964]: 10100K .......... .......... .......... .......... .......... 45% 3.24M 3s Sep 11 17:23:30 volumio volumio[964]: 10150K .......... .......... .......... .......... .......... 45% 4.91M 3s Sep 11 17:23:30 volumio volumio[964]: 10200K .......... .......... .......... .......... .......... 45% 4.39M 3s Sep 11 17:23:30 volumio volumio[964]: 10250K .......... .......... .......... .......... .......... 45% 1.44M 3s Sep 11 17:23:30 volumio volumio[964]: 10300K .......... .......... .......... .......... .......... 46% 3.22M 3s Sep 11 17:23:30 volumio volumio[964]: 10350K .......... .......... .......... .......... .......... 46% 1.77M 3s Sep 11 17:23:30 volumio volumio[964]: 10400K .......... .......... .......... .......... .......... 46% 4.32M 3s Sep 11 17:23:30 volumio volumio[964]: 10450K .......... .......... .......... .......... .......... 46% 3.79M 3s Sep 11 17:23:30 volumio volumio[964]: 10500K .......... .......... .......... .......... .......... 46% 3.06M 3s Sep 11 17:23:30 volumio volumio[964]: 10550K .......... .......... .......... .......... .......... 47% 6.12M 3s Sep 11 17:23:30 volumio volumio[964]: 10600K .......... .......... .......... .......... .......... 47% 4.22M 3s Sep 11 17:23:30 volumio volumio[964]: 10650K .......... .......... .......... .......... .......... 47% 5.15M 3s Sep 11 17:23:30 volumio volumio[964]: 10700K .......... .......... .......... .......... .......... 47% 921K 3s Sep 11 17:23:30 volumio volumio[964]: 10750K .......... .......... .......... .......... .......... 48% 2.93M 3s Sep 11 17:23:30 volumio volumio[964]: 10800K .......... .......... .......... .......... .......... 48% 4.89M 3s Sep 11 17:23:30 volumio volumio[964]: 10850K .......... .......... .......... .......... .......... 48% 6.71M 3s Sep 11 17:23:30 volumio volumio[964]: 10900K .......... .......... .......... .......... .......... 48% 6.30M 3s Sep 11 17:23:30 volumio volumio[964]: 10950K .......... .......... .......... .......... .......... 48% 5.79M 3s Sep 11 17:23:30 volumio volumio[964]: 11000K .......... .......... .......... .......... .......... 49% 4.28M 3s Sep 11 17:23:30 volumio volumio[964]: 11050K .......... .......... .......... .......... .......... 49% 4.36M 3s Sep 11 17:23:31 volumio volumio[964]: 11100K .......... .......... .......... .......... .......... 49% 4.72M 3s Sep 11 17:23:31 volumio volumio[964]: 11150K .......... .......... .......... .......... .......... 49% 3.59M 3s Sep 11 17:23:31 volumio volumio[964]: 11200K .......... .......... .......... .......... .......... 50% 2.88M 3s Sep 11 17:23:31 volumio volumio[964]: 11250K .......... .......... .......... .......... .......... 50% 5.22M 3s Sep 11 17:23:31 volumio volumio[964]: 11300K .......... .......... .......... .......... .......... 50% 4.84M 3s Sep 11 17:23:31 volumio volumio[964]: 11350K .......... .......... .......... .......... .......... 50% 3.90M 3s Sep 11 17:23:31 volumio volumio[964]: 11400K .......... .......... .......... .......... .......... 50% 4.32M 3s Sep 11 17:23:31 volumio volumio[964]: 11450K .......... .......... .......... .......... .......... 51% 4.48M 3s Sep 11 17:23:31 volumio volumio[964]: 11500K .......... .......... .......... .......... .......... 51% 3.93M 3s Sep 11 17:23:31 volumio volumio[964]: 11550K .......... .......... .......... .......... .......... 51% 5.62M 3s Sep 11 17:23:31 volumio volumio[964]: 11600K .......... .......... .......... .......... .......... 51% 4.58M 3s Sep 11 17:23:31 volumio volumio[964]: 11650K .......... .......... .......... .......... .......... 52% 6.62M 3s Sep 11 17:23:31 volumio volumio[964]: 11700K .......... .......... .......... .......... .......... 52% 4.12M 3s Sep 11 17:23:31 volumio volumio[964]: 11750K .......... .......... .......... .......... .......... 52% 6.11M 3s Sep 11 17:23:31 volumio volumio[964]: 11800K .......... .......... .......... .......... .......... 52% 6.05M 3s Sep 11 17:23:31 volumio volumio[964]: 11850K .......... .......... .......... .......... .......... 52% 6.29M 3s Sep 11 17:23:31 volumio volumio[964]: 11900K .......... .......... .......... .......... .......... 53% 5.44M 3s Sep 11 17:23:31 volumio volumio[964]: 11950K .......... .......... .......... .......... .......... 53% 6.68M 3s Sep 11 17:23:31 volumio volumio[964]: 12000K .......... .......... .......... .......... .......... 53% 2.43M 3s Sep 11 17:23:31 volumio volumio[964]: 12050K .......... .......... .......... .......... .......... 53% 4.58M 2s Sep 11 17:23:31 volumio volumio[964]: 12100K .......... .......... .......... .......... .......... 54% 6.97M 2s Sep 11 17:23:31 volumio volumio[964]: 12150K .......... .......... .......... .......... .......... 54% 3.00M 2s Sep 11 17:23:31 volumio volumio[964]: 12200K .......... .......... .......... .......... .......... 54% 4.90M 2s Sep 11 17:23:31 volumio volumio[964]: 12250K .......... .......... .......... .......... .......... 54% 8.23M 2s Sep 11 17:23:31 volumio volumio[964]: 12300K .......... .......... .......... .......... .......... 54% 2.76M 2s Sep 11 17:23:31 volumio volumio[964]: 12350K .......... .......... .......... .......... .......... 55% 4.00M 2s Sep 11 17:23:31 volumio volumio[964]: 12400K .......... .......... .......... .......... .......... 55% 2.44M 2s Sep 11 17:23:31 volumio volumio[964]: 12450K .......... .......... .......... .......... .......... 55% 5.73M 2s Sep 11 17:23:31 volumio volumio[964]: 12500K .......... .......... .......... .......... .......... 55% 5.12M 2s Sep 11 17:23:31 volumio volumio[964]: 12550K .......... .......... .......... .......... .......... 56% 4.59M 2s Sep 11 17:23:31 volumio volumio[964]: 12600K .......... .......... .......... .......... .......... 56% 5.62M 2s Sep 11 17:23:31 volumio volumio[964]: 12650K .......... .......... .......... .......... .......... 56% 4.23M 2s Sep 11 17:23:31 volumio volumio[964]: 12700K .......... .......... .......... .......... .......... 56% 5.87M 2s Sep 11 17:23:31 volumio volumio[964]: 12750K .......... .......... .......... .......... .......... 56% 1.90M 2s Sep 11 17:23:31 volumio volumio[964]: 12800K .......... .......... .......... .......... .......... 57% 3.76M 2s Sep 11 17:23:31 volumio volumio[964]: 12850K .......... .......... .......... .......... .......... 57% 3.53M 2s Sep 11 17:23:31 volumio volumio[964]: 12900K .......... .......... .......... .......... .......... 57% 2.18M 2s Sep 11 17:23:31 volumio volumio[964]: 12950K .......... .......... .......... .......... .......... 57% 2.30M 2s Sep 11 17:23:31 volumio volumio[964]: 13000K .......... .......... .......... .......... .......... 58% 9.87M 2s Sep 11 17:23:31 volumio volumio[964]: 13050K .......... .......... .......... .......... .......... 58% 8.42M 2s Sep 11 17:23:31 volumio volumio[964]: 13100K .......... .......... .......... .......... .......... 58% 4.48M 2s Sep 11 17:23:31 volumio volumio[964]: 13150K .......... .......... .......... .......... .......... 58% 2.95M 2s Sep 11 17:23:31 volumio volumio[964]: 13200K .......... .......... .......... .......... .......... 58% 2.29M 2s Sep 11 17:23:31 volumio volumio[964]: 13250K .......... .......... .......... .......... .......... 59% 2.70M 2s Sep 11 17:23:31 volumio volumio[964]: 13300K .......... .......... .......... .......... .......... 59% 5.00M 2s Sep 11 17:23:31 volumio volumio[964]: 13350K .......... .......... .......... .......... .......... 59% 2.82M 2s Sep 11 17:23:31 volumio volumio[964]: 13400K .......... .......... .......... .......... .......... 59% 3.26M 2s Sep 11 17:23:31 volumio volumio[964]: 13450K .......... .......... .......... .......... .......... 60% 6.96M 2s Sep 11 17:23:31 volumio volumio[964]: 13500K .......... .......... .......... .......... .......... 60% 4.73M 2s Sep 11 17:23:31 volumio volumio[964]: 13550K .......... .......... .......... .......... .......... 60% 4.16M 2s Sep 11 17:23:31 volumio volumio[964]: 13600K .......... .......... .......... .......... .......... 60% 3.52M 2s Sep 11 17:23:31 volumio volumio[964]: 13650K .......... .......... .......... .......... .......... 60% 7.00M 2s Sep 11 17:23:31 volumio volumio[964]: 13700K .......... .......... .......... .......... .......... 61% 4.60M 2s Sep 11 17:23:31 volumio volumio[964]: 13750K .......... .......... .......... .......... .......... 61% 6.44M 2s Sep 11 17:23:31 volumio volumio[964]: 13800K .......... .......... .......... .......... .......... 61% 2.04M 2s Sep 11 17:23:31 volumio volumio[964]: 13850K .......... .......... .......... .......... .......... 61% 5.34M 2s Sep 11 17:23:31 volumio volumio[964]: 13900K .......... .......... .......... .......... .......... 62% 6.53M 2s Sep 11 17:23:31 volumio volumio[964]: 13950K .......... .......... .......... .......... .......... 62% 4.46M 2s Sep 11 17:23:31 volumio volumio[964]: 14000K .......... .......... .......... .......... .......... 62% 3.43M 2s Sep 11 17:23:31 volumio volumio[964]: 14050K .......... .......... .......... .......... .......... 62% 3.23M 2s Sep 11 17:23:31 volumio volumio[964]: 14100K .......... .......... .......... .......... .......... 62% 5.40M 2s Sep 11 17:23:31 volumio volumio[964]: 14150K .......... .......... .......... .......... .......... 63% 3.55M 2s Sep 11 17:23:31 volumio volumio[964]: 14200K .......... .......... .......... .......... .......... 63% 2.72M 2s Sep 11 17:23:31 volumio volumio[964]: 14250K .......... .......... .......... .......... .......... 63% 6.03M 2s Sep 11 17:23:31 volumio volumio[964]: 14300K .......... .......... .......... .......... .......... 63% 5.25M 2s Sep 11 17:23:31 volumio volumio[964]: 14350K .......... .......... .......... .......... .......... 64% 4.61M 2s Sep 11 17:23:31 volumio volumio[964]: 14400K .......... .......... .......... .......... .......... 64% 3.70M 2s Sep 11 17:23:31 volumio volumio[964]: 14450K .......... .......... .......... .......... .......... 64% 3.66M 2s Sep 11 17:23:31 volumio volumio[964]: 14500K .......... .......... .......... .......... .......... 64% 5.72M 2s Sep 11 17:23:31 volumio volumio[964]: 14550K .......... .......... .......... .......... .......... 64% 6.49M 2s Sep 11 17:23:31 volumio volumio[964]: 14600K .......... .......... .......... .......... .......... 65% 6.67M 2s Sep 11 17:23:31 volumio volumio[964]: 14650K .......... .......... .......... .......... .......... 65% 4.80M 2s Sep 11 17:23:31 volumio volumio[964]: 14700K .......... .......... .......... .......... .......... 65% 2.69M 2s Sep 11 17:23:31 volumio volumio[964]: 14750K .......... .......... .......... .......... .......... 65% 9.07M 2s Sep 11 17:23:31 volumio volumio[964]: 14800K .......... .......... .......... .......... .......... 66% 5.73M 2s Sep 11 17:23:31 volumio volumio[964]: 14850K .......... .......... .......... .......... .......... 66% 7.34M 2s Sep 11 17:23:31 volumio volumio[964]: 14900K .......... .......... .......... .......... .......... 66% 4.72M 2s Sep 11 17:23:31 volumio volumio[964]: 14950K .......... .......... .......... .......... .......... 66% 6.16M 2s Sep 11 17:23:31 volumio volumio[964]: 15000K .......... .......... .......... .......... .......... 66% 857K 2s Sep 11 17:23:31 volumio volumio[964]: 15050K .......... .......... .......... .......... .......... 67% 2.33M 2s Sep 11 17:23:31 volumio volumio[964]: 15100K .......... .......... .......... .......... .......... 67% 4.46M 2s Sep 11 17:23:31 volumio volumio[964]: 15150K .......... .......... .......... .......... .......... 67% 4.40M 2s Sep 11 17:23:31 volumio volumio[964]: 15200K .......... .......... .......... .......... .......... 67% 3.05M 2s Sep 11 17:23:31 volumio volumio[964]: 15250K .......... .......... .......... .......... .......... 68% 5.56M 2s Sep 11 17:23:31 volumio volumio[964]: 15300K .......... .......... .......... .......... .......... 68% 8.14M 2s Sep 11 17:23:31 volumio volumio[964]: 15350K .......... .......... .......... .......... .......... 68% 7.49M 2s Sep 11 17:23:31 volumio volumio[964]: 15400K .......... .......... .......... .......... .......... 68% 4.95M 2s Sep 11 17:23:31 volumio volumio[964]: 15450K .......... .......... .......... .......... .......... 68% 2.85M 2s Sep 11 17:23:31 volumio volumio[964]: 15500K .......... .......... .......... .......... .......... 69% 2.44M 2s Sep 11 17:23:31 volumio volumio[964]: 15550K .......... .......... .......... .......... .......... 69% 8.77M 2s Sep 11 17:23:31 volumio volumio[964]: 15600K .......... .......... .......... .......... .......... 69% 4.24M 2s Sep 11 17:23:31 volumio volumio[964]: 15650K .......... .......... .......... .......... .......... 69% 2.10M 2s Sep 11 17:23:31 volumio volumio[964]: 15700K .......... .......... .......... .......... .......... 70% 10.1M 2s Sep 11 17:23:31 volumio volumio[964]: 15750K .......... .......... .......... .......... .......... 70% 7.58M 2s Sep 11 17:23:31 volumio volumio[964]: 15800K .......... .......... .......... .......... .......... 70% 4.89M 2s Sep 11 17:23:31 volumio volumio[964]: 15850K .......... .......... .......... .......... .......... 70% 4.54M 2s Sep 11 17:23:31 volumio volumio[964]: 15900K .......... .......... .......... .......... .......... 70% 6.84M 2s Sep 11 17:23:31 volumio volumio[964]: 15950K .......... .......... .......... .......... .......... 71% 7.58M 2s Sep 11 17:23:31 volumio volumio[964]: 16000K .......... .......... .......... .......... .......... 71% 1.83M 2s Sep 11 17:23:31 volumio volumio[964]: 16050K .......... .......... .......... .......... .......... 71% 1.70M 2s Sep 11 17:23:31 volumio volumio[964]: 16100K .......... .......... .......... .......... .......... 71% 3.73M 2s Sep 11 17:23:31 volumio volumio[964]: 16150K .......... .......... .......... .......... .......... 72% 3.03M 2s Sep 11 17:23:31 volumio volumio[964]: 16200K .......... .......... .......... .......... .......... 72% 5.54M 2s Sep 11 17:23:31 volumio volumio[964]: 16250K .......... .......... .......... .......... .......... 72% 5.43M 2s Sep 11 17:23:31 volumio volumio[964]: 16300K .......... .......... .......... .......... .......... 72% 3.59M 2s Sep 11 17:23:31 volumio volumio[964]: 16350K .......... .......... .......... .......... .......... 72% 4.04M 1s Sep 11 17:23:31 volumio volumio[964]: 16400K .......... .......... .......... .......... .......... 73% 3.40M 1s Sep 11 17:23:31 volumio volumio[964]: 16450K .......... .......... .......... .......... .......... 73% 4.12M 1s Sep 11 17:23:31 volumio volumio[964]: 16500K .......... .......... .......... .......... .......... 73% 4.67M 1s Sep 11 17:23:31 volumio volumio[964]: 16550K .......... .......... .......... .......... .......... 73% 1.99M 1s Sep 11 17:23:31 volumio volumio[964]: 16600K .......... .......... .......... .......... .......... 74% 4.74M 1s Sep 11 17:23:31 volumio volumio[964]: 16650K .......... .......... .......... .......... .......... 74% 4.42M 1s Sep 11 17:23:31 volumio volumio[964]: 16700K .......... .......... .......... .......... .......... 74% 7.48M 1s Sep 11 17:23:31 volumio volumio[964]: 16750K .......... .......... .......... .......... .......... 74% 7.78M 1s Sep 11 17:23:31 volumio volumio[964]: 16800K .......... .......... .......... .......... .......... 74% 2.83M 1s Sep 11 17:23:31 volumio volumio[964]: 16850K .......... .......... .......... .......... .......... 75% 3.91M 1s Sep 11 17:23:31 volumio volumio[964]: 16900K .......... .......... .......... .......... .......... 75% 5.82M 1s Sep 11 17:23:31 volumio volumio[964]: 16950K .......... .......... .......... .......... .......... 75% 7.19M 1s Sep 11 17:23:31 volumio volumio[964]: 17000K .......... .......... .......... .......... .......... 75% 7.88M 1s Sep 11 17:23:31 volumio volumio[964]: 17050K .......... .......... .......... .......... .......... 76% 8.35M 1s Sep 11 17:23:31 volumio volumio[964]: 17100K .......... .......... .......... .......... .......... 76% 2.44M 1s Sep 11 17:23:31 volumio volumio[964]: 17150K .......... .......... .......... .......... .......... 76% 3.78M 1s Sep 11 17:23:31 volumio volumio[964]: 17200K .......... .......... .......... .......... .......... 76% 5.53M 1s Sep 11 17:23:31 volumio volumio[964]: 17250K .......... .......... .......... .......... .......... 76% 5.39M 1s Sep 11 17:23:31 volumio volumio[964]: 17300K .......... .......... .......... .......... .......... 77% 1.57M 1s Sep 11 17:23:31 volumio volumio[964]: 17350K .......... .......... .......... .......... .......... 77% 8.85M 1s Sep 11 17:23:31 volumio volumio[964]: 17400K .......... .......... .......... .......... .......... 77% 5.47M 1s Sep 11 17:23:31 volumio volumio[964]: 17450K .......... .......... .......... .......... .......... 77% 7.57M 1s Sep 11 17:23:31 volumio volumio[964]: 17500K .......... .......... .......... .......... .......... 78% 2.66M 1s Sep 11 17:23:31 volumio volumio[964]: 17550K .......... .......... .......... .......... .......... 78% 2.48M 1s Sep 11 17:23:31 volumio volumio[964]: 17600K .......... .......... .......... .......... .......... 78% 4.37M 1s Sep 11 17:23:31 volumio volumio[964]: 17650K .......... .......... .......... .......... .......... 78% 2.43M 1s Sep 11 17:23:31 volumio volumio[964]: 17700K .......... .......... .......... .......... .......... 78% 4.97M 1s Sep 11 17:23:31 volumio volumio[964]: 17750K .......... .......... .......... .......... .......... 79% 4.03M 1s Sep 11 17:23:31 volumio volumio[964]: 17800K .......... .......... .......... .......... .......... 79% 7.33M 1s Sep 11 17:23:31 volumio volumio[964]: 17850K .......... .......... .......... .......... .......... 79% 6.93M 1s Sep 11 17:23:31 volumio volumio[964]: 17900K .......... .......... .......... .......... .......... 79% 3.96M 1s Sep 11 17:23:31 volumio volumio[964]: 17950K .......... .......... .......... .......... .......... 80% 7.13M 1s Sep 11 17:23:31 volumio volumio[964]: 18000K .......... .......... .......... .......... .......... 80% 3.30M 1s Sep 11 17:23:31 volumio volumio[964]: 18050K .......... .......... .......... .......... .......... 80% 6.10M 1s Sep 11 17:23:31 volumio volumio[964]: 18100K .......... .......... .......... .......... .......... 80% 3.47M 1s Sep 11 17:23:31 volumio volumio[964]: 18150K .......... .......... .......... .......... .......... 80% 4.38M 1s Sep 11 17:23:31 volumio volumio[964]: 18200K .......... .......... .......... .......... .......... 81% 4.72M 1s Sep 11 17:23:31 volumio volumio[964]: 18250K .......... .......... .......... .......... .......... 81% 3.87M 1s Sep 11 17:23:31 volumio volumio[964]: 18300K .......... .......... .......... .......... .......... 81% 6.96M 1s Sep 11 17:23:31 volumio volumio[964]: 18350K .......... .......... .......... .......... .......... 81% 4.15M 1s Sep 11 17:23:31 volumio volumio[964]: 18400K .......... .......... .......... .......... .......... 82% 5.33M 1s Sep 11 17:23:31 volumio volumio[964]: 18450K .......... .......... .......... .......... .......... 82% 3.73M 1s Sep 11 17:23:31 volumio volumio[964]: 18500K .......... .......... .......... .......... .......... 82% 6.93M 1s Sep 11 17:23:31 volumio volumio[964]: 18550K .......... .......... .......... .......... .......... 82% 5.77M 1s Sep 11 17:23:31 volumio volumio[964]: 18600K .......... .......... .......... .......... .......... 82% 1.75M 1s Sep 11 17:23:31 volumio volumio[964]: 18650K .......... .......... .......... .......... .......... 83% 6.21M 1s Sep 11 17:23:31 volumio volumio[964]: 18700K .......... .......... .......... .......... .......... 83% 1.75M 1s Sep 11 17:23:31 volumio volumio[964]: 18750K .......... .......... .......... .......... .......... 83% 2.14M 1s Sep 11 17:23:31 volumio volumio[964]: 18800K .......... .......... .......... .......... .......... 83% 3.25M 1s Sep 11 17:23:31 volumio volumio[964]: 18850K .......... .......... .......... .......... .......... 84% 6.11M 1s Sep 11 17:23:31 volumio volumio[964]: 18900K .......... .......... .......... .......... .......... 84% 2.32M 1s Sep 11 17:23:31 volumio volumio[964]: 18950K .......... .......... .......... .......... .......... 84% 4.45M 1s Sep 11 17:23:31 volumio volumio[964]: 19000K .......... .......... .......... .......... .......... 84% 5.38M 1s Sep 11 17:23:31 volumio volumio[964]: 19050K .......... .......... .......... .......... .......... 84% 4.23M 1s Sep 11 17:23:31 volumio volumio[964]: 19100K .......... .......... .......... .......... .......... 85% 4.75M 1s Sep 11 17:23:31 volumio volumio[964]: 19150K .......... .......... .......... .......... .......... 85% 4.17M 1s Sep 11 17:23:31 volumio volumio[964]: 19200K .......... .......... .......... .......... .......... 85% 5.02M 1s Sep 11 17:23:31 volumio volumio[964]: 19250K .......... .......... .......... .......... .......... 85% 8.35M 1s Sep 11 17:23:31 volumio volumio[964]: 19300K .......... .......... .......... .......... .......... 86% 4.60M 1s Sep 11 17:23:31 volumio volumio[964]: 19350K .......... .......... .......... .......... .......... 86% 1.39M 1s Sep 11 17:23:31 volumio volumio[964]: 19400K .......... .......... .......... .......... .......... 86% 5.62M 1s Sep 11 17:23:31 volumio volumio[964]: 19450K .......... .......... .......... .......... .......... 86% 5.80M 1s Sep 11 17:23:31 volumio volumio[964]: 19500K .......... .......... .......... .......... .......... 86% 5.15M 1s Sep 11 17:23:31 volumio volumio[964]: 19550K .......... .......... .......... .......... .......... 87% 7.06M 1s Sep 11 17:23:31 volumio volumio[964]: 19600K .......... .......... .......... .......... .......... 87% 6.32M 1s Sep 11 17:23:31 volumio volumio[964]: 19650K .......... .......... .......... .......... .......... 87% 8.11M 1s Sep 11 17:23:31 volumio volumio[964]: 19700K .......... .......... .......... .......... .......... 87% 4.35M 1s Sep 11 17:23:31 volumio volumio[964]: 19750K .......... .......... .......... .......... .......... 88% 5.27M 1s Sep 11 17:23:31 volumio volumio[964]: 19800K .......... .......... .......... .......... .......... 88% 5.95M 1s Sep 11 17:23:31 volumio volumio[964]: 19850K .......... .......... .......... .......... .......... 88% 5.39M 1s Sep 11 17:23:31 volumio volumio[964]: 19900K .......... .......... .......... .......... .......... 88% 3.60M 1s Sep 11 17:23:31 volumio volumio[964]: 19950K .......... .......... .......... .......... .......... 88% 5.44M 1s Sep 11 17:23:31 volumio volumio[964]: 20000K .......... .......... .......... .......... .......... 89% 5.40M 1s Sep 11 17:23:31 volumio volumio[964]: 20050K .......... .......... .......... .......... .......... 89% 4.64M 1s Sep 11 17:23:31 volumio volumio[964]: 20100K .......... .......... .......... .......... .......... 89% 7.26M 1s Sep 11 17:23:31 volumio volumio[964]: 20150K .......... .......... .......... .......... .......... 89% 2.22M 1s Sep 11 17:23:31 volumio volumio[964]: 20200K .......... .......... .......... .......... .......... 90% 2.14M 1s Sep 11 17:23:31 volumio volumio[964]: 20250K .......... .......... .......... .......... .......... 90% 5.88M 1s Sep 11 17:23:31 volumio volumio[964]: 20300K .......... .......... .......... .......... .......... 90% 1.62M 1s Sep 11 17:23:31 volumio volumio[964]: 20350K .......... .......... .......... .......... .......... 90% 8.30M 1s Sep 11 17:23:31 volumio volumio[964]: 20400K .......... .......... .......... .......... .......... 90% 3.81M 0s Sep 11 17:23:31 volumio volumio[964]: 20450K .......... .......... .......... .......... .......... 91% 4.11M 0s Sep 11 17:23:31 volumio volumio[964]: 20500K .......... .......... .......... .......... .......... 91% 2.40M 0s Sep 11 17:23:31 volumio volumio[964]: 20550K .......... .......... .......... .......... .......... 91% 4.12M 0s Sep 11 17:23:31 volumio volumio[964]: 20600K .......... .......... .......... .......... .......... 91% 2.72M 0s Sep 11 17:23:31 volumio volumio[964]: 20650K .......... .......... .......... .......... .......... 92% 3.74M 0s Sep 11 17:23:31 volumio volumio[964]: 20700K .......... .......... .......... .......... .......... 92% 4.39M 0s Sep 11 17:23:31 volumio volumio[964]: 20750K .......... .......... .......... .......... .......... 92% 3.56M 0s Sep 11 17:23:31 volumio volumio[964]: 20800K .......... .......... .......... .......... .......... 92% 1.68M 0s Sep 11 17:23:31 volumio volumio[964]: 20850K .......... .......... .......... .......... .......... 92% 6.30M 0s Sep 11 17:23:31 volumio volumio[964]: 20900K .......... .......... .......... .......... .......... 93% 2.99M 0s Sep 11 17:23:31 volumio volumio[964]: 20950K .......... .......... .......... .......... .......... 93% 3.05M 0s Sep 11 17:23:31 volumio volumio[964]: 21000K .......... .......... .......... .......... .......... 93% 4.35M 0s Sep 11 17:23:31 volumio volumio[964]: 21050K .......... .......... .......... .......... .......... 93% 4.57M 0s Sep 11 17:23:31 volumio volumio[964]: 21100K .......... .......... .......... .......... .......... 94% 4.84M 0s Sep 11 17:23:31 volumio volumio[964]: 21150K .......... .......... .......... .......... .......... 94% 6.39M 0s Sep 11 17:23:31 volumio volumio[964]: 21200K .......... .......... .......... .......... .......... 94% 4.77M 0s Sep 11 17:23:31 volumio volumio[964]: 21250K .......... .......... .......... .......... .......... 94% 6.13M 0s Sep 11 17:23:31 volumio volumio[964]: 21300K .......... .......... .......... .......... .......... 94% 5.24M 0s Sep 11 17:23:31 volumio volumio[964]: 21350K .......... .......... .......... .......... .......... 95% 3.85M 0s Sep 11 17:23:31 volumio volumio[964]: 21400K .......... .......... .......... .......... .......... 95% 2.49M 0s Sep 11 17:23:31 volumio volumio[964]: 21450K .......... .......... .......... .......... .......... 95% 7.46M 0s Sep 11 17:23:31 volumio volumio[964]: 21500K .......... .......... .......... .......... .......... 95% 4.40M 0s Sep 11 17:23:31 volumio volumio[964]: 21550K .......... .......... .......... .......... .......... 96% 3.29M 0s Sep 11 17:23:31 volumio volumio[964]: 21600K .......... .......... .......... .......... .......... 96% 4.38M 0s Sep 11 17:23:31 volumio volumio[964]: 21650K .......... .......... .......... .......... .......... 96% 6.97M 0s Sep 11 17:23:31 volumio volumio[964]: 21700K .......... .......... .......... .......... .......... 96% 3.47M 0s Sep 11 17:23:31 volumio volumio[964]: 21750K .......... .......... .......... .......... .......... 96% 4.49M 0s Sep 11 17:23:31 volumio volumio[964]: 21800K .......... .......... .......... .......... .......... 97% 5.43M 0s Sep 11 17:23:31 volumio volumio[964]: 21850K .......... .......... .......... .......... .......... 97% 3.22M 0s Sep 11 17:23:31 volumio volumio[964]: 21900K .......... .......... .......... .......... .......... 97% 6.24M 0s Sep 11 17:23:31 volumio volumio[964]: 21950K .......... .......... .......... .......... .......... 97% 5.39M 0s Sep 11 17:23:31 volumio volumio[964]: 22000K .......... .......... .......... .......... .......... 98% 1.48M 0s Sep 11 17:23:31 volumio volumio[964]: 22050K .......... .......... .......... .......... .......... 98% 9.97M 0s Sep 11 17:23:31 volumio volumio[964]: 22100K .......... .......... .......... .......... .......... 98% 5.93M 0s Sep 11 17:23:31 volumio volumio[964]: 22150K .......... .......... .......... .......... .......... 98% 5.57M 0s Sep 11 17:23:31 volumio volumio[964]: 22200K .......... .......... .......... .......... .......... 98% 4.40M 0s Sep 11 17:23:31 volumio volumio[964]: 22250K .......... .......... .......... .......... .......... 99% 3.08M 0s Sep 11 17:23:31 volumio volumio[964]: 22300K .......... .......... .......... .......... .......... 99% 6.12M 0s Sep 11 17:23:31 volumio volumio[964]: 22350K .......... .......... .......... .......... .......... 99% 4.80M 0s Sep 11 17:23:31 volumio volumio[964]: 22400K .......... .......... .......... .......... .......... 99% 4.09M 0s Sep 11 17:23:31 volumio volumio[964]: 22450K .......... .......... .......... .......... ..... 100% 4.77M=5.5s Sep 11 17:23:31 volumio volumio[964]: 2025-09-11 17:23:30 (3.99 MB/s) - ‘/tmp/tools.tar.xz’ saved [23035700/23035700] Sep 11 17:23:32 volumio go-librespot[1225]: time="2025-09-11T17:23:32+09:00" level=trace msg="sent dealer ping" Sep 11 17:23:32 volumio go-librespot[1225]: time="2025-09-11T17:23:32+09:00" level=trace msg="received dealer pong" Sep 11 17:23:35 volumio volumio-remote-updater[642]: [2025-09-11 17:23:35] [connect] Successful connection Sep 11 17:23:37 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Sep 11 17:23:37 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Sep 11 17:23:37 volumio volumio-remote-updater[642]: [2025-09-11 17:23:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757579015 101 Sep 11 17:23:37 volumio volumio[964]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Sep 11 17:23:37 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:23:37 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:37 volumio volumio[964]: info: FusionDsp - Choose a preset Sep 11 17:23:37 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:23:38 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 11 17:23:38 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 11 17:23:38 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:38 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:42 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Sep 11 17:23:42 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand update Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:42 volumio volumio[964]: info: Sep 11 17:23:42 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:42 volumio volumio[964]: info: sendMpdCommand update took 7 milliseconds Sep 11 17:23:42 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:42 volumio volumio[964]: info: Sep 11 17:23:42 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:42 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:42 volumio volumio[964]: info: sendMpdCommand status took 10 milliseconds Sep 11 17:23:42 volumio volumio[964]: info: sendMpdCommand status took 9 milliseconds Sep 11 17:23:42 volumio volumio[964]: info: sendMpdCommand status took 7 milliseconds Sep 11 17:23:42 volumio volumio[964]: info: sendMpdCommand status took 7 milliseconds Sep 11 17:23:42 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: info: Command Router : Notfying DB Updatetrue Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: info: Command Router : Notfying DB Updatetrue Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 17:23:45 volumio volumio[964]: verbose: CURRENT POSITION 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::syncState stateService stop Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: No code Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 17:23:45 volumio volumio[964]: verbose: CURRENT POSITION 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::syncState stateService stop Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: No code Sep 11 17:23:45 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:45 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:45 volumio volumio[964]: info: ------------------------------ 3064ms Sep 11 17:23:45 volumio volumio[964]: info: ------------------------------ 3061ms Sep 11 17:23:45 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 11 17:23:45 volumio volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:45 volumio volumio[964]: info: Sep 11 17:23:45 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:45 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:45 volumio volumio[964]: info: Sep 11 17:23:45 volumio volumio[964]: ---------------------------- MPD announces state update: update Sep 11 17:23:45 volumio volumio[964]: info: ControllerMpd::getState Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::sendMpdCommand status Sep 11 17:23:45 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Sep 11 17:23:45 volumio volumio[964]: info: sendMpdCommand status took 33 milliseconds Sep 11 17:23:45 volumio volumio[964]: info: sendMpdCommand status took 31 milliseconds Sep 11 17:23:45 volumio volumio[964]: info: sendMpdCommand status took 30 milliseconds Sep 11 17:23:45 volumio volumio[964]: info: sendMpdCommand status took 35 milliseconds Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: info: Command Router : Notfying DB Updatefalse Sep 11 17:23:45 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:45 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:46 volumio volumio[964]: info: Command Router : Notfying DB Updatefalse Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:23:46 volumio volumio[964]: verbose: ControllerMpd::parseState Sep 11 17:23:46 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 17:23:46 volumio volumio[964]: verbose: CURRENT POSITION 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::syncState stateService stop Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: No code Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: ControllerMpd::pushState Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::servicePushState Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 11 17:23:46 volumio volumio[964]: verbose: CURRENT POSITION 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::syncState stateService stop Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::syncState currentStatus stop Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: No code Sep 11 17:23:46 volumio volumio[964]: info: CoreStateMachine::pushState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:46 volumio volumio[964]: info: ------------------------------ 124ms Sep 11 17:23:46 volumio volumio[964]: info: ------------------------------ 121ms Sep 11 17:23:46 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:46 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:46 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:23:51 volumio volumio[964]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:51 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:51 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetQueue Sep 11 17:23:51 volumio volumio[964]: info: CoreStateMachine::getQueue Sep 11 17:23:51 volumio volumio[964]: info: CorePlayQueue::getQueue Sep 11 17:23:51 volumio volumio[964]: info: Listing playlists Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 11 17:23:51 volumio volumio[964]: info: Received Get System Info Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 11 17:23:51 volumio volumio[964]: info: Discovery: Getting this device information Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:51 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::volumioGetState Sep 11 17:23:51 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:23:51 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 11 17:23:51 volumio volumio[964]: info: FusionDsp - Choose a preset Sep 11 17:23:51 volumio volumio[964]: info: FusionDsp - Choose a preset Sep 11 17:23:51 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:23:52 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:23:53 volumio volumio[964]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::ClearQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::clearPlayQueue Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::addQueueItems Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::addQueueItems Sep 11 17:23:53 volumio volumio[964]: info: Preload queue cleared Sep 11 17:23:53 volumio volumio[964]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/undefined Sep 11 17:23:53 volumio volumio[964]: info: Using cached record of: INTERNAL/FusionDsp/tools/undefined Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioClearQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::ClearQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::clearPlayQueue Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioPushState Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioPushQueue Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::saveQueue Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::updateTrackBlock Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::getTrackBlock Sep 11 17:23:53 volumio volumio[964]: info: CoreCommandRouter::volumioPlay Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::play index 0 Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::stop Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::play index undefined Sep 11 17:23:53 volumio volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 17:23:53 volumio volumio[964]: info: CorePlayQueue::getTrack 0 Sep 11 17:23:53 volumio volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Sep 11 17:23:53 volumio volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Sep 11 17:23:53 volumio volumio[964]: SPOTIFY: VOLUMIO VOLUME 50 Sep 11 17:23:53 volumio volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 11 17:24:02 volumio go-librespot[1225]: time="2025-09-11T17:24:02+09:00" level=trace msg="sent dealer ping" Sep 11 17:24:02 volumio go-librespot[1225]: time="2025-09-11T17:24:02+09:00" level=trace msg="received dealer pong" Sep 11 17:24:02 volumio volumio[964]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Sep 11 17:24:02 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Sep 11 17:24:02 volumio volumio[964]: info: FusionDsp - Value for usedpreset: Sep 11 17:24:02 volumio volumio[964]: error: FusionDsp - Value reading JSON file: {"geq15":"-0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5","x2geq15":"-0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5","state4preset":["None",false,false,50,0,0,0,"None",true,false,false,0,0,false]} Sep 11 17:24:02 volumio volumio[964]: info: FusionDsp - geq15 -0.5,1,1.5,1,0.5,1.5,1,0.5,-0.5,-1,-1,1,2,2,1.5 Sep 11 17:24:02 volumio volumio[964]: info: FusionDsp - value state4preset None,false,false,50,0,0,0,None,true,false,false,0,0,false Sep 11 17:24:03 volumio volumio[964]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 11 17:24:03 volumio volumio[964]: error: FusionDsp - WebSocket error: [object Object] Sep 11 17:24:03 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:03 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:24:03 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:03 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:04 volumio nmbd[764]: [2025/09/11 17:24:04.832605, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 11 17:24:04 volumio nmbd[764]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.32 for name WORKGROUP<1d>. Sep 11 17:24:04 volumio nmbd[764]: This response was from IP 192.168.1.28, reporting an IP address of 192.168.1.28. Sep 11 17:24:07 volumio go-librespot[1225]: time="2025-09-11T17:24:07+09:00" level=trace msg="received accesspoint ping" Sep 11 17:24:08 volumio go-librespot[1225]: time="2025-09-11T17:24:08+09:00" level=trace msg="received accesspoint pong ack" Sep 11 17:24:11 volumio volumio[964]: info: CALLMETHOD: audio_interface fusiondsp reseteq Sep 11 17:24:11 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Sep 11 17:24:11 volumio volumio[964]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 11 17:24:11 volumio volumio[964]: error: FusionDsp - WebSocket error: [object Object] Sep 11 17:24:11 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:11 volumio volumio[964]: info: CoreCommandRouter::Close All Modals sent Sep 11 17:24:11 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:11 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:18 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 11 17:24:18 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 11 17:24:18 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:18 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:18 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:19 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:19 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:19 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:22 volumio volumio[964]: info: Enabling plugin fusiondsp Sep 11 17:24:22 volumio volumio[964]: info: Loading plugin "fusiondsp"... Sep 11 17:24:22 volumio volumio[964]: info: Applying required configuration parameters for plugin fusiondsp Sep 11 17:24:22 volumio volumio[964]: info: Preparing to generate the ALSA configuration file Sep 11 17:24:22 volumio volumio[964]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 11 17:24:22 volumio volumio[964]: info: Reading ALSA contributions from plugins. Sep 11 17:24:22 volumio volumio[964]: info: Asound.conf file unchanged, so no further update is needed Sep 11 17:24:22 volumio volumio[964]: info: Output device has changed, restarting MPD Sep 11 17:24:22 volumio sudo[26668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 11 17:24:22 volumio sudo[26668]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:22 volumio volumio[964]: info: Output device has changed, restarting Shairport Sync Sep 11 17:24:22 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:22 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 17:24:22 volumio sudo[26668]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:22 volumio sudo[26671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 11 17:24:22 volumio sudo[26671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:22 volumio systemd[1]: Stopping Music Player Daemon... Sep 11 17:24:22 volumio volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 11 17:24:22 volumio volumio[964]: info: PLUGIN START: fusiondsp Sep 11 17:24:22 volumio volumio[964]: info: Loading i18n strings for locale ja Sep 11 17:24:22 volumio systemd[1]: mpd.service: Succeeded. Sep 11 17:24:22 volumio systemd[1]: Stopped Music Player Daemon. Sep 11 17:24:22 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 11 17:24:22 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:22 volumio volumio[964]: info: FusionDsp - mixtype--------------------- Hardware Sep 11 17:24:22 volumio volumio[964]: info: Preparing to generate the ALSA configuration file Sep 11 17:24:23 volumio volumio[964]: info: Done. Sep 11 17:24:23 volumio volumio[964]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 11 17:24:23 volumio volumio[964]: info: Reading ALSA contributions from plugins. Sep 11 17:24:23 volumio volumio[964]: info: MPD Permissions set Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio systemd[1]: Starting Music Player Daemon... Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 17:24:23 volumio volumio[964]: info: Starting Shairport Sync Sep 11 17:24:23 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:23 volumio sudo[26683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 11 17:24:23 volumio sudo[26683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 11 17:24:23 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 11 17:24:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 11 17:24:23 volumio sudo[26677]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 11 17:24:23 volumio sudo[26677]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 11 17:24:23 volumio sudo[26677]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:23 volumio sudo[26683]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:23 volumio volumio[964]: info: FusionDsp - Rock-FusionDsp Sep 11 17:24:23 volumio volumio[964]: info: Asound.conf file unchanged, so no further update is needed Sep 11 17:24:23 volumio volumio[964]: info: Output device has changed, restarting MPD Sep 11 17:24:23 volumio sudo[26692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 11 17:24:23 volumio sudo[26692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:23 volumio volumio[964]: info: Output device has changed, restarting Shairport Sync Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 17:24:23 volumio sudo[26692]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:23 volumio sudo[26695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 11 17:24:23 volumio sudo[26695]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:23 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 11 17:24:23 volumio systemd[1]: mpd.service: Succeeded. Sep 11 17:24:23 volumio systemd[1]: Stopped Music Player Daemon. Sep 11 17:24:23 volumio volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 11 17:24:23 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:23 volumio systemd[1]: Starting Music Player Daemon... Sep 11 17:24:23 volumio volumio[964]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Sep 11 17:24:23 volumio volumio[964]: info: Shairport-Sync Started Sep 11 17:24:23 volumio volumio[964]: info: MPD Permissions set Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 11 17:24:23 volumio volumio[964]: info: Starting Shairport Sync Sep 11 17:24:23 volumio sudo[26700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 11 17:24:23 volumio sudo[26700]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:23 volumio sudo[26700]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:23 volumio sudo[26708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 11 17:24:23 volumio sudo[26708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 17:24:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 11 17:24:24 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 11 17:24:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 11 17:24:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 11 17:24:24 volumio sudo[26708]: pam_unix(sudo:session): session closed for user root Sep 11 17:24:24 volumio volumio[964]: info: Shairport-Sync Started Sep 11 17:24:24 volumio volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 17:24:24 volumio volumio[964]: TypeError: Cannot read property 'off' of undefined Sep 11 17:24:24 volumio volumio[964]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 11 17:24:24 volumio volumio[964]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 11 17:24:24 volumio volumio[964]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 11 17:24:24 volumio volumio[964]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 11 17:24:24 volumio volumio[964]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 11 17:24:24 volumio volumio[964]: at Socket.emit (events.js:315:20) Sep 11 17:24:24 volumio volumio[964]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 11 17:24:24 volumio volumio[964]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 11 17:24:24 volumio volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 17:24:25 volumio sudo[26722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 17:23 Sep 11 17:24:25 volumio sudo[26722]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"