-- Logs begin at Thu 2019-02-14 12:12:01 EET, end at Mon 2024-12-02 17:46:51 EET. -- Dec 02 17:45:00 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:00 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:00 black volumio[22982]: info: Volumio called home Dec 02 17:45:00 black volumio[22982]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 02 17:45:00 black volumio[22982]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 02 17:45:00 black volumio[22982]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 02 17:45:00 black volumio[22982]: info: camilladsp spawned new process with pid 23215, instance 1, run: true Dec 02 17:45:00 black volumio[22982]: info: camilladsp service started and running in background, instance 1 Dec 02 17:45:00 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:00 black volumio[22982]: cannot open device 'volumioHw': Device or resource busy Dec 02 17:45:00 black volumio[22982]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 02 17:45:00 black volumio[22982]: cannot open device 'volumioHw': Device or resource busy Dec 02 17:45:00 black volumio[22982]: info: FusionDsp loaded Dec 02 17:45:00 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:00 black sudo[23225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 02 17:45:00 black sudo[23225]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:00 black volumio[22982]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 02 17:45:00 black volumio[22982]: info: Adding Signal Path Element [object Object] Dec 02 17:45:00 black volumio[22982]: info: Adding fusiondspeq DSP Signal Path Element Dec 02 17:45:00 black volumio[22982]: info: FusionDsp - ---- installed callbackRead Dec 02 17:45:00 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:00 black sudo[23225]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:00 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:00 black volumio[22982]: info: Discovery: Connected to remote: 192.168.100.5 Dec 02 17:45:01 black mpd[23179]: Dec 02 17:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:45:01 black systemd[1]: Started Music Player Daemon. Dec 02 17:45:01 black sudo[23161]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black sudo[23151]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black volumio[22982]: info: Starting Shairport Sync Dec 02 17:45:01 black volumio[22982]: info: Starting Shairport Sync Dec 02 17:45:01 black volumio[22982]: info: Starting Shairport Sync Dec 02 17:45:01 black sudo[23231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:45:01 black sudo[23231]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:01 black sudo[23233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:45:01 black sudo[23233]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:01 black volumio[22982]: info: An error occurred while refreshing Spotify Token Error: Bad Request Dec 02 17:45:01 black systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:45:01 black sudo[23236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:45:01 black systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:45:01 black sudo[23236]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:01 black systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:01 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:01 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:01 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:01 black systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:01 black volumio[22982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 02 17:45:01 black sudo[23231]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black sudo[23233]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:45:01 black systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 17:45:01 black systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:45:01 black systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:01 black systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:01 black sudo[23236]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:01 black volumio[22982]: info: Shairport-Sync Started Dec 02 17:45:01 black volumio[22982]: Error adding Membership: Error: addMembership EINVAL Dec 02 17:45:01 black volumio[22982]: info: Shairport-Sync Started Dec 02 17:45:01 black volumio[22982]: info: Shairport-Sync Started Dec 02 17:45:01 black volumio[22982]: info: Asound.conf file unchanged, so no further update is needed Dec 02 17:45:01 black volumio[22982]: info: Output device has changed, restarting MPD Dec 02 17:45:01 black volumio[22982]: info: Output device has changed, restarting Shairport Sync Dec 02 17:45:01 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:01 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:01 black sudo[23251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:45:01 black sudo[23251]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:01 black sudo[23251]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:01 black sudo[23253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:45:01 black sudo[23253]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:01 black systemd[1]: Stopping Music Player Daemon... Dec 02 17:45:01 black volumio[22982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:45:01 black systemd[1]: mpd.service: Succeeded. Dec 02 17:45:01 black systemd[1]: Stopped Music Player Daemon. Dec 02 17:45:01 black volumio[22982]: error: MPD error: Error: write EPIPE Dec 02 17:45:01 black volumio[22982]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 02 17:45:01 black systemd[1]: Starting Music Player Daemon... Dec 02 17:45:02 black volumio[22982]: error: MPD error: The expression evaluated to a falsy value: Dec 02 17:45:02 black volumio[22982]: assert.ok(self.idling) Dec 02 17:45:02 black volumio[22982]: error: The expression evaluated to a falsy value: Dec 02 17:45:02 black volumio[22982]: assert.ok(self.idling) Dec 02 17:45:02 black volumio[22982]: error: MPD error: Error: write EPIPE Dec 02 17:45:02 black volumio[22982]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 02 17:45:02 black volumio[22982]: info: MPD running with PID23179 Dec 02 17:45:02 black volumio[22982]: ,establishing connection Dec 02 17:45:02 black sudo[23259]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 02 17:45:02 black sudo[23259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:02 black sudo[23259]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:02 black volumio[22982]: [yt-cast-receiver] Listening on port 8098 Dec 02 17:45:02 black volumio[22982]: info: Completed starting Core Plugins Dec 02 17:45:02 black volumio[22982]: info: ------------------------------------------- Dec 02 17:45:02 black volumio[22982]: info: ----- MyVolumio plugins startup ---- Dec 02 17:45:02 black volumio[22982]: info: ------------------------------------------- Dec 02 17:45:02 black volumio[22982]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 17:45:02 black volumio[22982]: info: MPD Permissions set Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:02 black volumio[22982]: info: Starting Shairport Sync Dec 02 17:45:02 black sudo[23270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:45:02 black sudo[23270]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:02 black systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:45:02 black systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:45:02 black systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:02 black systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:45:02 black sudo[23270]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:02 black volumio[22982]: info: Shairport-Sync Started Dec 02 17:45:03 black volumio[22982]: info: go-librespot daemon successfully initialized Dec 02 17:45:04 black volumio[22982]: info: CoreCommandRouter::volumioPlay Dec 02 17:45:04 black volumio[22982]: info: CoreStateMachine::play index undefined Dec 02 17:45:04 black volumio[22982]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 17:45:04 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:04 black volumio[22982]: info: CoreStateMachine::startPlaybackTimer Dec 02 17:45:04 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:04 black volumio[22982]: info: [1733154304018] ControllerWebradio::clearAddPlayTrack Dec 02 17:45:04 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 17:45:04 black mpd[23264]: Dec 02 17:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:45:04 black systemd[1]: Started Music Player Daemon. Dec 02 17:45:04 black volumio[22982]: error: updateQueue error: null Dec 02 17:45:04 black sudo[23253]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:04 black volumio[22982]: info: sendMpdCommand stop took 582 milliseconds Dec 02 17:45:04 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 17:45:04 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:04 black volumio[22982]: info: Dec 02 17:45:04 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:45:04 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:45:04 black volumio[22982]: info: sendMpdCommand clear took 9 milliseconds Dec 02 17:45:04 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99534780" Dec 02 17:45:04 black volumio[22982]: error: updateQueue error: null Dec 02 17:45:04 black volumio[22982]: info: ------------------------------ 32ms Dec 02 17:45:05 black volumio[22982]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99534780" took 997 milliseconds Dec 02 17:45:05 black volumio[22982]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 17:45:05 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand play Dec 02 17:45:05 black volumio[22982]: info: Dec 02 17:45:05 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:45:05 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:45:05 black volumio[22982]: info: sendMpdCommand play took 11 milliseconds Dec 02 17:45:05 black volumio[22982]: info: ------------------------------ 12ms Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 02 17:45:06 black volumio[22982]: info: camilladsp stopping service pid 23215... Dec 02 17:45:06 black volumio[22982]: info: camilladsp service terminated, instance 1 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: camilladsp service started and running in background, instance 1 Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 114 milliseconds Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 111 milliseconds Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 38 milliseconds Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 27 milliseconds Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 11/300) Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus stop Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 11/300) Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:45:06 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 230ms Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 253ms Dec 02 17:45:06 black volumio[22982]: info: camilladsp spawned new process with pid 23298, instance 1, run: true Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:45:06 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 132 milliseconds Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 126 milliseconds Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 11/300) Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:45:06 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:45:06 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 334ms Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 318ms Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:45:06 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:45:06 black volumio[22982]: info: Dec 02 17:45:06 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: Initializing connection to go-librespot Websocket Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 318ms Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 313 milliseconds Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 161ms Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand status took 157 milliseconds Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 02 17:45:06 black volumio[22982]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:45:06 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:45:06 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:45:06 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:45:06 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:45:06 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:45:06 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:06 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 462ms Dec 02 17:45:06 black volumio[22982]: info: ------------------------------ 305ms Dec 02 17:45:06 black go-librespot[23190]: time="2024-12-02T17:45:06+02:00" level=debug msg="new websocket client" Dec 02 17:45:06 black volumio[22982]: info: Connection to go-librespot Websocket established Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - volume level for loudness 89 gain applied 0.00 Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:06 black volumio[22982]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:06 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:07 black volumio[23148]: writing RSA key Dec 02 17:45:09 black volumio[22982]: info: Getting Spotify volume Dec 02 17:45:09 black volumio[22982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:10 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Dec 02 17:45:10 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME undefined Dec 02 17:45:10 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 89 Dec 02 17:45:10 black volumio[22982]: info: Aligning Spotify Volume to Volumio Volume Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:10 black volumio[22982]: info: Setting Spotify Volume from Volumio: 89 Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:10 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 02 17:45:10 black volumio[22982]: info: Adding plugin bluetooth to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: Adding plugin multiroom to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: Adding plugin metavolumio to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: Adding plugin cd_controller to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 02 17:45:10 black volumio[22982]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 02 17:45:12 black volumio[22982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 02 17:45:12 black volumio[22982]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 02 17:45:12 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:12 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:12 black volumio[22982]: info: Starting MyVolumio Remote Streaming Endpoints Dec 02 17:45:12 black volumio[22982]: info: MyVolumio login type: Token Dec 02 17:45:12 black volumio[22982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 02 17:45:12 black volumio[22982]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 02 17:45:14 black volumio[22982]: info: Starting Streaming Service Transparent Proxy Dec 02 17:45:14 black volumio[22982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 02 17:45:14 black volumio[22982]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 02 17:45:14 black volumio[22982]: info: Streaming services startup Dec 02 17:45:14 black volumio[22982]: info: Starting Streaming Daemon Dec 02 17:45:14 black sudo[23405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 17:45:14 black sudo[23405]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:15 black volumio[22982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 02 17:45:15 black sudo[23405]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:15 black volumio[22982]: SPOTIFY: SETTING SPOTIFY VOLUME 89 Dec 02 17:45:15 black volumio[22982]: info: Sending Spotify command with payload to local API: /player/volume Dec 02 17:45:15 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:15 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 02 17:45:15 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:15 black volumio[22982]: error: Cannot start Volumio Streaming Daemon Dec 02 17:45:15 black volumio[22982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 17:45:15 black volumio[22982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 17:45:15 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:15 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:15 black volumio[22982]: error: MyVolumio Custom Token format not valid, refreshing it Dec 02 17:45:16 black volumio[22982]: info: MyVolumio login type: Token Dec 02 17:45:17 black volumio[22982]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 02 17:45:17 black volumio[22982]: STREAMING PROXY: Starting server on port 3245 Dec 02 17:45:17 black volumio[22982]: Node JS runtime: 14 Dec 02 17:45:17 black volumio[22982]: info: MyVolumio token set successfully Dec 02 17:45:17 black volumio[22982]: info: MYVOLUMIO: Adding device Dec 02 17:45:17 black volumio[22982]: info: MYVOLUMIO: Evaluating Server Dec 02 17:45:18 black volumio[22982]: info: MyVolumio status changed Dec 02 17:45:18 black volumio[22982]: info: Streaming services startup Dec 02 17:45:18 black volumio[22982]: info: Starting Streaming Daemon Dec 02 17:45:18 black volumio[22982]: info: Removing browser output: myVolumio user plan is not superstar Dec 02 17:45:18 black volumio[22982]: info: Removing audio output: Dec 02 17:45:18 black volumio[22982]: info: Stoppping Tunnel 1 Dec 02 17:45:18 black sudo[23449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 02 17:45:18 black sudo[23449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:18 black sudo[23452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 02 17:45:18 black sudo[23449]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:18 black sudo[23452]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:18 black volumio[22982]: error: Cannot start Volumio Streaming Daemon Dec 02 17:45:18 black volumio[22982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 02 17:45:18 black volumio[22982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 02 17:45:18 black sudo[23452]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:18 black volumio[22982]: info: Remote SSH Stopped Dec 02 17:45:18 black volumio[22982]: info: Setting Geolocation for MyVolumio to eu4 Dec 02 17:45:18 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:19 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:19 black volumio[22982]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 02 17:45:19 black volumio[22982]: info: Updating MyVolumio device info Dec 02 17:45:19 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:20 black volumio[22982]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 02 17:45:23 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:23 black volumio[22982]: info: MYVOLUMIO: Adding device Dec 02 17:45:23 black volumio[22982]: info: MYVOLUMIO: Evaluating Server Dec 02 17:45:24 black volumio[22982]: info: Setting Geolocation for MyVolumio to eu6 Dec 02 17:45:24 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:24 black volumio[22982]: info: Enabling MyMusic plugin bluetooth Dec 02 17:45:24 black volumio[22982]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Dec 02 17:45:24 black volumio[22982]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Dec 02 17:45:24 black volumio[22982]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Dec 02 17:45:24 black volumio[22982]: error: Could not Enable MyMusic Plugin: Error Dec 02 17:45:24 black volumio[22982]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 02 17:45:25 black volumio[22982]: info: Updating MyVolumio device info Dec 02 17:45:25 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:25 black volumio[22982]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 02 17:45:27 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:27 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 17:45:27 black volumio[22982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 02 17:45:27 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:28 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:45:28 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:28 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:45:28 black volumio-remote-updater[550]: No test mode Dec 02 17:45:28 black volumio-remote-updater[550]: No alpha test mode Dec 02 17:45:28 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 02 17:45:28 black volumio[22982]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 02 17:45:28 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 02 17:45:31 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:32 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:45:34 black sudo[23525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:45:34 black sudo[23525]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:34 black sudo[23527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:45:34 black sudo[23527]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:34 black sudo[23525]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:34 black sudo[23527]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:35 black volumio[22982]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 02 17:45:35 black volumio[22982]: info: Completed starting MyVolumio Plugin Dec 02 17:45:35 black volumio[22982]: [Metrics] CommandRouter: 53s 628.93ms Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumiosetStartupVolume Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:45:35 black volumio[22982]: info: VolumeController:: Setting startup Volume 50 Dec 02 17:45:35 black volumio[22982]: info: VolumeController::SetAlsaVolume50 Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:35 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Dec 02 17:45:35 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 89 Dec 02 17:45:35 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 50 Dec 02 17:45:35 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:35 black volumio[22982]: info: Setting Spotify Volume from Volumio: 50 Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:35 black volumio[22982]: verbose: New Socket.io Connection to 192.168.100.45 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 12; moto g pro Build/S0PRS32.44-11-10-26; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:35 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:35 black volumio[22982]: info: Received Get System Info Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:35 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 17:45:35 black volumio[22982]: info: Received Get System Info Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:35 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:35 black volumio[22982]: info: Listing playlists Dec 02 17:45:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 17:45:36 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:36 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:36 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:36 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:37 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 02 17:45:37 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 02 17:45:37 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 02 17:45:37 black volumio[22982]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Dec 02 17:45:37 black volumio[22982]: info: Sending Spotify command with payload to local API: /player/volume Dec 02 17:45:38 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 02 17:45:38 black volumio[22982]: info: Received Get System Info Dec 02 17:45:38 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:38 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:38 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:38 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:38 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:42 black volumio[22982]: info: BOOT COMPLETED Dec 02 17:45:45 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 17:45:45 black sudo[23574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:45:45 black sudo[23574]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:45 black sudo[23574]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:45 black sudo[23576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:45:45 black sudo[23576]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:45:45 black sudo[23576]: pam_unix(sudo:session): session closed for user root Dec 02 17:45:45 black volumio[22982]: verbose: New Socket.io Connection to 192.168.100.45 from 192.168.100.36 UA: Mozilla/5.0 (Linux; Android 12; moto g pro Build/S0PRS32.44-11-10-26; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 02 17:45:45 black volumio[22982]: info: Received Get System Info Dec 02 17:45:45 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:45 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:45 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 17:45:46 black volumio[22982]: info: Received Get System Info Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:46 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: Listing playlists Dec 02 17:45:46 black volumio[22982]: info: Received Get System Info Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:45:46 black volumio[22982]: info: Discovery: Getting this device information Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 02 17:45:46 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:47 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: info: Retrieving Cloud Streaming UI Dec 02 17:45:51 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:51 black volumio[22982]: Upnp client error: Error: This socket has been ended by the other party Dec 02 17:45:51 black volumio[22982]: info: Getting Tidal Cloud Configuration Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: info: Getting Qobuz Cloud Configuration Dec 02 17:45:51 black volumio[22982]: info: Asking plugin for UI Config Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: info: Getting Spotify Cloud Configuration Dec 02 17:45:51 black volumio[22982]: info: Asking plugin for UI Config Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: info: Saving Spotify Acccount Dec 02 17:45:51 black volumio[22982]: info: Got Tidal Cloud Configuration Dec 02 17:45:51 black volumio[22982]: info: Got it Dec 02 17:45:51 black volumio[22982]: info: Got it Dec 02 17:45:51 black volumio[22982]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 02 17:45:51 black volumio[22982]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:51 black volumio[22982]: info: CoreCommandRouter::volumioGetBrowseSources Dec 02 17:45:55 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 17:45:57 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:58 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - volume level for loudness 55 gain applied 0.00 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:58 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 50 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 55 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:58 black volumio[22982]: info: Setting Spotify Volume from Volumio: 55 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:58 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:58 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:58 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 55 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 60 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:58 black volumio[22982]: info: Setting Spotify Volume from Volumio: 60 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:58 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:58 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - volume level for loudness 65 gain applied 0.00 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:58 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 60 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 65 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:58 black volumio[22982]: info: Setting Spotify Volume from Volumio: 65 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:58 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:58 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:58 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - volume level for loudness 70 gain applied 0.00 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:58 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 65 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 70 Dec 02 17:45:58 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:58 black volumio[22982]: info: Setting Spotify Volume from Volumio: 70 Dec 02 17:45:58 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 02 17:45:59 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:59 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:59 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:45:59 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:45:59 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - volume level for loudness 80 gain applied 0.00 Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - volume level for loudness 80 gain applied 0.00 Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:45:59 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 02 17:45:59 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 70 Dec 02 17:45:59 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 80 Dec 02 17:45:59 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:45:59 black volumio[22982]: info: Setting Spotify Volume from Volumio: 80 Dec 02 17:45:59 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 02 17:45:59 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:01 black volumio[22982]: SPOTIFY: SETTING SPOTIFY VOLUME 80 Dec 02 17:46:01 black volumio[22982]: info: Sending Spotify command with payload to local API: /player/volume Dec 02 17:46:01 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:46:01 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:46:01 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:01 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:01 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:01 black volumio[22982]: info: FusionDsp - volume level for loudness 85 gain applied 0.00 Dec 02 17:46:01 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:01 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:01 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Dec 02 17:46:01 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 80 Dec 02 17:46:01 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 85 Dec 02 17:46:01 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:46:01 black volumio[22982]: info: Setting Spotify Volume from Volumio: 85 Dec 02 17:46:01 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:02 black volumio[22982]: SPOTIFY: SETTING SPOTIFY VOLUME 85 Dec 02 17:46:02 black volumio[22982]: info: Sending Spotify command with payload to local API: /player/volume Dec 02 17:46:03 black volumio[22982]: info: VolumeController::SetAlsaVolume+ Dec 02 17:46:03 black volumio[22982]: info: CoreCommandRouter::volumioGetState Dec 02 17:46:03 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:03 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:03 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:03 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:03 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:03 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:03 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:03 black volumio[22982]: SPOTIFY: SPOTIFY VOLUME 85 Dec 02 17:46:03 black volumio[22982]: SPOTIFY: VOLUMIO VOLUME 90 Dec 02 17:46:03 black volumio[22982]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 02 17:46:03 black volumio[22982]: info: Setting Spotify Volume from Volumio: 90 Dec 02 17:46:03 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:03 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:04 black volumio[22982]: SPOTIFY: SETTING SPOTIFY VOLUME 90 Dec 02 17:46:04 black volumio[22982]: info: Sending Spotify command with payload to local API: /player/volume Dec 02 17:46:07 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:11 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:15 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:19 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:23 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:27 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:31 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:35 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand update Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:35 black volumio[22982]: info: Dec 02 17:46:35 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:35 black volumio[22982]: info: sendMpdCommand update took 16 milliseconds Dec 02 17:46:35 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:35 black volumio[22982]: info: Dec 02 17:46:35 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:35 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:35 black volumio[22982]: info: sendMpdCommand status took 23 milliseconds Dec 02 17:46:35 black volumio[22982]: info: sendMpdCommand status took 13 milliseconds Dec 02 17:46:35 black volumio[22982]: info: sendMpdCommand status took 12 milliseconds Dec 02 17:46:35 black volumio[22982]: info: sendMpdCommand status took 7 milliseconds Dec 02 17:46:35 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:35 black volumio[22982]: info: Command Router : Notfying DB Updatetrue Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: info: Command Router : Notfying DB Updatetrue Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:36 black volumio[22982]: info: Dec 02 17:46:36 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:36 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:36 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:36 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:36 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:36 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:36 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":85980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:36 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:36 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: ------------------------------ 454ms Dec 02 17:46:36 black volumio[22982]: info: ------------------------------ 445ms Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:36 black volumio[22982]: info: Dec 02 17:46:36 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand status took 286 milliseconds Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand status took 283 milliseconds Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand status took 101 milliseconds Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand status took 98 milliseconds Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand playlistinfo took 110 milliseconds Dec 02 17:46:36 black volumio[22982]: info: sendMpdCommand playlistinfo took 66 milliseconds Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:36 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:36 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:36 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86220,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:36 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:36 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:36 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:36 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":86471,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Genesis - Down And Out","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:36 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:36 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:36 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:36 black volumio[22982]: info: ------------------------------ 646ms Dec 02 17:46:36 black volumio[22982]: info: ------------------------------ 462ms Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:36 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:39 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:39 black volumio[22982]: info: Dec 02 17:46:39 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:46:39 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:46:39 black volumio[22982]: info: Dec 02 17:46:39 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:46:39 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:39 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: Dec 02 17:46:40 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:46:40 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:46:40 black volumio[22982]: info: Dec 02 17:46:40 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: ------------------------------ 24ms Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 18 milliseconds Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:40 black volumio[22982]: info: ------------------------------ 20ms Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 14 milliseconds Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:40 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:40 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90094,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:40 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:40 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:40 black volumio[22982]: info: ------------------------------ 121ms Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand playlistinfo took 93 milliseconds Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:40 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:40 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90094,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:40 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:40 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:40 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:40 black volumio[22982]: info: ------------------------------ 208ms Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:40 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:40 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:40 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:40 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:40 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand rescan Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: Dec 02 17:46:40 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand rescan took 69 milliseconds Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: Dec 02 17:46:40 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: Dec 02 17:46:40 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:40 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 58 milliseconds Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 55 milliseconds Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 54 milliseconds Dec 02 17:46:40 black volumio[22982]: info: sendMpdCommand status took 51 milliseconds Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:40 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:40 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:40 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:41 black volumio[22982]: info: Dec 02 17:46:41 black volumio[22982]: ---------------------------- MPD announces state update: update Dec 02 17:46:41 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand status took 187 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand status took 185 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand playlistinfo took 104 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand status took 19 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand status took 17 milliseconds Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: info: Command Router : Notfying DB Updatefalse Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:41 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:41 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:41 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90847,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:41 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:41 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:41 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:41 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90847,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:41 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:41 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: ------------------------------ 551ms Dec 02 17:46:41 black volumio[22982]: info: ------------------------------ 547ms Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand playlistinfo took 328 milliseconds Dec 02 17:46:41 black volumio[22982]: info: sendMpdCommand playlistinfo took 279 milliseconds Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:41 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:41 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:41 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:41 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90973,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:41 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:41 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:41 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:41 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91224,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Unknown - Prog Frog Queen","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:41 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:41 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:41 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:41 black volumio[22982]: info: ------------------------------ 734ms Dec 02 17:46:41 black volumio[22982]: info: ------------------------------ 559ms Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - volume level for loudness 90 gain applied 0.00 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - Loudness is ON true Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:41 black volumio[22982]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 02 17:46:43 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:44 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 02 17:46:44 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 02 17:46:47 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 02 17:46:50 black volumio[22982]: info: Dec 02 17:46:50 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:46:50 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:46:50 black volumio[22982]: info: Dec 02 17:46:50 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:46:50 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:50 black volumio[22982]: info: Dec 02 17:46:50 black volumio[22982]: ---------------------------- MPD announces system playlist update Dec 02 17:46:50 black volumio[22982]: info: Ignoring MPD Status Update Dec 02 17:46:50 black volumio[22982]: info: Dec 02 17:46:50 black volumio[22982]: ---------------------------- MPD announces state update: player Dec 02 17:46:50 black volumio[22982]: info: ControllerMpd::getState Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand status Dec 02 17:46:50 black volumio[22982]: info: ------------------------------ 18ms Dec 02 17:46:50 black volumio[22982]: info: sendMpdCommand status took 14 milliseconds Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:50 black volumio[22982]: info: ------------------------------ 18ms Dec 02 17:46:50 black volumio[22982]: info: sendMpdCommand status took 14 milliseconds Dec 02 17:46:50 black volumio[22982]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::parseState Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:50 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:50 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:50 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100093,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"echolyn - Meaning And The Moment","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:50 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:50 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:50 black volumio[22982]: info: ------------------------------ 109ms Dec 02 17:46:50 black volumio[22982]: info: sendMpdCommand playlistinfo took 83 milliseconds Dec 02 17:46:50 black volumio[22982]: verbose: ControllerMpd::parseTrackInfo Dec 02 17:46:50 black volumio[22982]: info: ControllerMpd::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::servicePushState Dec 02 17:46:50 black volumio[22982]: info: CorePlayQueue::getTrack 0 Dec 02 17:46:50 black volumio[22982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100218,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"echolyn - Meaning And The Moment","artist":"Prog Frog","album":null,"uri":"http://216.126.196.154:4040/stream","trackType":""} Dec 02 17:46:50 black volumio[22982]: verbose: CURRENT POSITION 0 Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::syncState stateService play Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::syncState currentStatus play Dec 02 17:46:50 black volumio[22982]: info: Received an update from plugin. extracting info from payload Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:50 black volumio[22982]: info: CoreStateMachine::pushState Dec 02 17:46:50 black volumio[22982]: info: CoreCommandRouter::volumioPushState Dec 02 17:46:50 black volumio[22982]: info: ------------------------------ 178ms Dec 02 17:46:50 black volumio[22982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:46:50 black volumio[22982]: Error: dns service error: unknown Dec 02 17:46:50 black volumio[22982]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Dec 02 17:46:50 black volumio[22982]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 02 17:46:50 black volumio[22982]: errorCode: -65537 Dec 02 17:46:50 black volumio[22982]: } Dec 02 17:46:50 black volumio[22982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:46:51 black sudo[23927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 17:45 Dec 02 17:46:51 black sudo[23927]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"