-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-12-10 19:09:18 CET. -- Dec 10 19:08:02 minidsp-shd volumio[893]: info: Loading plugin "tidal"... Dec 10 19:08:04 minidsp-shd volumio[893]: info: Loading plugin "oem_helper"... Dec 10 19:08:04 minidsp-shd systemd[1]: systemd-timedated.service: Succeeded. Dec 10 19:08:06 minidsp-shd volumio[893]: info: Applying required configuration parameters for plugin oem_helper Dec 10 19:08:06 minidsp-shd volumio-remote-updater[580]: [2025-12-10 19:08:06] [connect] Successful connection Dec 10 19:08:06 minidsp-shd volumio[893]: info: Loading plugin "updater_comm"... Dec 10 19:08:06 minidsp-shd volumio[893]: info: Plugin mpdemulation is not enabled Dec 10 19:08:06 minidsp-shd volumio[893]: info: Loading plugin "rest_api"... Dec 10 19:08:06 minidsp-shd volumio[893]: info: Loading plugin "websocket"... Dec 10 19:08:06 minidsp-shd volumio[893]: info: Starting Socket.io Server version 2.3.0 Dec 10 19:08:07 minidsp-shd volumio[893]: info: Loading i18n strings for locale de Dec 10 19:08:07 minidsp-shd volumio[893]: Updating browse sources language Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::initPlayerControls Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 19:08:07 minidsp-shd volumio[893]: Express server listening on port 3000 Dec 10 19:08:07 minidsp-shd volumio[893]: [Metrics] WebUI: 33s 684.46ms Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreStateMachine::resetVolumioState Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreStateMachine::getcurrentVolume Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:07 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:07 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:07 minidsp-shd sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 19:08:07 minidsp-shd sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:07 minidsp-shd sudo[1139]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:07 minidsp-shd sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 19:08:07 minidsp-shd sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:07 minidsp-shd sudo[1141]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:07 minidsp-shd volumio[893]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 10 19:08:07 minidsp-shd volumio[893]: info: Completed loading Core Plugins Dec 10 19:08:07 minidsp-shd volumio[893]: info: Preparing to generate the ALSA configuration file Dec 10 19:08:07 minidsp-shd volumio[893]: info: Volumio Network Manager: Network status updated: 2 Dec 10 19:08:07 minidsp-shd volumio[893]: info: MRS: Removed streaming files Dec 10 19:08:07 minidsp-shd volumio[893]: info: MRS: volumioStreaming STOPPED Dec 10 19:08:07 minidsp-shd volumio[893]: info: MRS: SNAPSERVER STOPPED Dec 10 19:08:07 minidsp-shd volumio[893]: info: MRS: SNAPCLIENT STOPPED Dec 10 19:08:07 minidsp-shd volumio[893]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 10 19:08:07 minidsp-shd volumio[893]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 19:08:07 minidsp-shd volumio[893]: info: Reading ALSA contributions from plugins. Dec 10 19:08:08 minidsp-shd volumio-remote-updater[580]: [2025-12-10 19:08:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765390086 101 Dec 10 19:08:08 minidsp-shd volumio[893]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Dec 10 19:08:08 minidsp-shd volumio[893]: info: Reloading queue from file Dec 10 19:08:08 minidsp-shd volumio[893]: info: Setting Device type: NanoPi NEO 3 Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreStateMachine::setRepeat null single undefined Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:08 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreStateMachine::setRandom null Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:08 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:08 minidsp-shd volumio[893]: info: Asound.conf file unchanged, so no further update is needed Dec 10 19:08:08 minidsp-shd volumio[893]: info: Output device has changed, restarting MPD Dec 10 19:08:08 minidsp-shd sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 10 19:08:08 minidsp-shd sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:08 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Dec 10 19:08:08 minidsp-shd sudo[1150]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:08 minidsp-shd sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 19:08:08 minidsp-shd sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:08 minidsp-shd volumio[893]: info: Output device has changed, restarting Shairport Sync Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:08 minidsp-shd sudo[1153]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:08 minidsp-shd sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 19:08:08 minidsp-shd sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:08 minidsp-shd systemd[1]: Listening on mpd.socket. Dec 10 19:08:08 minidsp-shd volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 19:08:08 minidsp-shd volumio[893]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 19:08:08 minidsp-shd systemd[1]: Starting Music Player Daemon... Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:08 minidsp-shd volumio[893]: info: ___________ START PLUGINS ___________ Dec 10 19:08:08 minidsp-shd volumio[893]: info: ControllerMpd::onStart: Initializing MPD Dec 10 19:08:08 minidsp-shd volumio[893]: info: Creating MPD Configuration file Dec 10 19:08:08 minidsp-shd sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 19:08:08 minidsp-shd sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 19:08:08 minidsp-shd volumio[893]: info: [1765390088995] CoreMusicLibrary::Adding element Medienserver Dec 10 19:08:08 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:09 minidsp-shd sudo[1166]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:09 minidsp-shd sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 19:08:09 minidsp-shd sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:09 minidsp-shd sudo[1162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 19:08:09 minidsp-shd sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:09 minidsp-shd sudo[1162]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 10 19:08:09 minidsp-shd sudo[1162]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding METAVOLUMIO REST API Endpoints Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 10 19:08:09 minidsp-shd systemd[1]: mpd.service: Succeeded. Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 10 19:08:09 minidsp-shd systemd[1]: Stopped Music Player Daemon. Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:09 minidsp-shd systemd[1]: Starting Music Player Daemon... Dec 10 19:08:09 minidsp-shd volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:09 minidsp-shd volumio[893]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 19:08:09 minidsp-shd volumio[893]: info: Preparing CD Folders Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding CD REST API Endpoints Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 10 19:08:09 minidsp-shd volumio[893]: info: Starting UDEV Watcher for CD Dec 10 19:08:09 minidsp-shd volumio[893]: info: Detecting CD presence with UDEV Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 10 19:08:09 minidsp-shd sudo[1172]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 19:08:09 minidsp-shd sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:09 minidsp-shd sudo[1172]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 10 19:08:09 minidsp-shd sudo[1172]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 19:08:09 minidsp-shd volumio[893]: info: [1765390089333] CoreMusicLibrary::Adding element Last_100 Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:09 minidsp-shd volumio[893]: info: Streaming services startup Dec 10 19:08:09 minidsp-shd volumio[893]: info: Starting Streaming Daemon Dec 10 19:08:09 minidsp-shd sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 10 19:08:09 minidsp-shd sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:09 minidsp-shd sudo[1182]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 19:08:09 minidsp-shd volumio[893]: info: [1765390089476] CoreMusicLibrary::Adding element Webradio Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:09 minidsp-shd volumio[893]: info: Initializing BBC Radios Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 10 19:08:09 minidsp-shd volumio[893]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:09 minidsp-shd dbus-daemon[597]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.10' (uid=1000 pid=893 comm="/usr/bin/node /volumio/index.js ") Dec 10 19:08:09 minidsp-shd volumio[893]: error: Hi Res Audio Failed Login: Missing Login Data Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 10 19:08:09 minidsp-shd volumio[893]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 10 19:08:09 minidsp-shd systemd[1]: Starting Bluetooth service... Dec 10 19:08:09 minidsp-shd bluetoothd[1201]: Bluetooth daemon 5.23 Dec 10 19:08:09 minidsp-shd dbus-daemon[597]: [system] Successfully activated service 'org.bluez' Dec 10 19:08:09 minidsp-shd systemd[1]: Started Bluetooth service. Dec 10 19:08:09 minidsp-shd bluetoothd[1201]: Starting SDP server Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:09 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:09 minidsp-shd bluetoothd[1201]: kernel lacks bnep-protocol support Dec 10 19:08:09 minidsp-shd bluetoothd[1201]: System does not support network plugin Dec 10 19:08:10 minidsp-shd volumio[893]: info: Volumio Calling Home Dec 10 19:08:10 minidsp-shd bluetoothd[1201]: Bluetooth management interface 1.14 initialized Dec 10 19:08:10 minidsp-shd dbus-daemon[597]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.13' (uid=0 pid=1201 comm="/usr/local/libexec/bluetooth/bluetoothd ") Dec 10 19:08:10 minidsp-shd systemd[1]: Starting Hostname Service... Dec 10 19:08:10 minidsp-shd volumio[893]: info: Stopping AccessToken refresher cron for QOBUZ Dec 10 19:08:10 minidsp-shd dbus-daemon[597]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 10 19:08:10 minidsp-shd systemd[1]: Started Hostname Service. Dec 10 19:08:10 minidsp-shd volumio[893]: info: AccessToken refresher cron started for QOBUZ Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding TIDAL REST API Endpoints Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding QOBUZ REST API Endpoints Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 10 19:08:10 minidsp-shd volumio[893]: info: Discovery: adding a68f1b83-79c7-4ecd-895e-48e3554199f2 Dec 10 19:08:10 minidsp-shd volumio[893]: info: Discovery: Found device miniDSP SHD Dec 10 19:08:10 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetState Dec 10 19:08:10 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:10 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output for this device Dec 10 19:08:10 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding audio output: Dec 10 19:08:10 minidsp-shd volumio[893]: info: Adding audio output: Dec 10 19:08:10 minidsp-shd volumio[893]: info: Serial port opened successfully Dec 10 19:08:10 minidsp-shd volumio[893]: error: Cannot start Volumio Streaming Daemon Dec 10 19:08:10 minidsp-shd volumio[893]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 10 19:08:10 minidsp-shd volumio[893]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 10 19:08:10 minidsp-shd volumio[893]: info: MPD Permissions set Dec 10 19:08:10 minidsp-shd volumio[893]: info: MPD Permissions set Dec 10 19:08:10 minidsp-shd volumio[893]: info: Upmpdcli Daemon Started Dec 10 19:08:10 minidsp-shd volumio[893]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Dec 10 19:08:10 minidsp-shd volumio[893]: info: [msSurfaceDial init()] check /org/bluez Dec 10 19:08:11 minidsp-shd volumio[893]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Dec 10 19:08:11 minidsp-shd volumio[893]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::Reload Ui Dec 10 19:08:11 minidsp-shd volumio[893]: info: Volumio called home Dec 10 19:08:11 minidsp-shd volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Dec 10 19:08:11 minidsp-shd volumio[893]: info: Adding MINIDSP Inputs Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 19:08:11 minidsp-shd volumio[893]: info: [1765390091507] CoreMusicLibrary::Adding element Inputs Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 10 19:08:11 minidsp-shd volumio[893]: info: [1765390091517] CoreMusicLibrary::Adding element Presets Dec 10 19:08:11 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:11 minidsp-shd volumio[893]: Cannot find translation for source Presets Dec 10 19:08:11 minidsp-shd volumio[893]: info: Starting Shairport Sync Dec 10 19:08:11 minidsp-shd volumio[893]: info: Starting Shairport Sync Dec 10 19:08:12 minidsp-shd volumio[893]: info: Starting Shairport Sync Dec 10 19:08:12 minidsp-shd sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 19:08:12 minidsp-shd sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:12 minidsp-shd sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 19:08:12 minidsp-shd sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:12 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 19:08:12 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Dec 10 19:08:12 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetState Dec 10 19:08:12 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:12 minidsp-shd sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 19:08:12 minidsp-shd sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:12 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 19:08:12 minidsp-shd sudo[1245]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:12 minidsp-shd sudo[1247]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:12 minidsp-shd volumio[893]: Cannot find translation for source Presets Dec 10 19:08:12 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 19:08:12 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 10 19:08:12 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Dec 10 19:08:12 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 19:08:12 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 19:08:12 minidsp-shd sudo[1251]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:12 minidsp-shd sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 19:08:12 minidsp-shd sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:12 minidsp-shd sudo[1258]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:12 minidsp-shd sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 19:08:12 minidsp-shd sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:12 minidsp-shd sudo[1260]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:12 minidsp-shd volumio[893]: info: Shairport-Sync Started Dec 10 19:08:12 minidsp-shd volumio[893]: Error adding Membership: Error: addMembership EINVAL Dec 10 19:08:12 minidsp-shd volumio[893]: info: Shairport-Sync Started Dec 10 19:08:12 minidsp-shd volumio[893]: info: Shairport-Sync Started Dec 10 19:08:12 minidsp-shd volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 10 19:08:12 minidsp-shd volumio[893]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.183 UA: Mozilla/5.0 (Android 15; Mobile; rv:146.0) Gecko/146.0 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 19:08:12 minidsp-shd volumio[893]: info: Enabling external Volume Control Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:12 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:13 minidsp-shd sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 10 19:08:13 minidsp-shd sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:13 minidsp-shd sudo[1295]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:13 minidsp-shd sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 10 19:08:13 minidsp-shd sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:13 minidsp-shd sudo[1301]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:13 minidsp-shd volumio[893]: verbose: New Socket.io Connection to 192.168.0.31 from 192.168.0.183 UA: Mozilla/5.0 (Android 15; Mobile; rv:146.0) Gecko/146.0 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 10 19:08:13 minidsp-shd volumio[893]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} Dec 10 19:08:13 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:13 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:13 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:13 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:13 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:13 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:13 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetState Dec 10 19:08:13 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 10 19:08:14 minidsp-shd volumio[893]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 10 19:08:14 minidsp-shd volumio[893]: info: Listing playlists Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 19:08:14 minidsp-shd volumio[893]: info: MRS: Getting audio outputs on start Dec 10 19:08:14 minidsp-shd volumio[893]: info: MRS: Requesting all other devices output Dec 10 19:08:14 minidsp-shd volumio[893]: info: msSurfaceDial volumioupdatevolume callback: {"vol":85,"dbVolume":-19,"mute":false,"disableVolumeControl":false} Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:14 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:14 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:14 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:14 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 10 19:08:15 minidsp-shd mpd[1180]: Dec 10 19:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 19:08:15 minidsp-shd systemd[1]: Started Music Player Daemon. Dec 10 19:08:15 minidsp-shd volumio[893]: Upnp client error: Error: This socket has been ended by the other party Dec 10 19:08:15 minidsp-shd sudo[1168]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:15 minidsp-shd sudo[1156]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:15 minidsp-shd volumio[893]: info: Completed starting Core Plugins Dec 10 19:08:15 minidsp-shd volumio[893]: info: ------------------------------------------- Dec 10 19:08:15 minidsp-shd volumio[893]: info: ----- MyVolumio plugins startup ---- Dec 10 19:08:15 minidsp-shd volumio[893]: info: ------------------------------------------- Dec 10 19:08:15 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 10 19:08:15 minidsp-shd volumio[893]: error: MPD error: The expression evaluated to a falsy value: Dec 10 19:08:15 minidsp-shd volumio[893]: assert.ok(self.idling) Dec 10 19:08:15 minidsp-shd volumio[893]: error: The expression evaluated to a falsy value: Dec 10 19:08:15 minidsp-shd volumio[893]: assert.ok(self.idling) Dec 10 19:08:15 minidsp-shd volumio[893]: info: MPD running with PID1180 Dec 10 19:08:15 minidsp-shd volumio[893]: ,establishing connection Dec 10 19:08:15 minidsp-shd volumio[893]: error: updateQueue error: null Dec 10 19:08:15 minidsp-shd volumio[893]: error: updateQueue error: null Dec 10 19:08:18 minidsp-shd sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 19:08:18 minidsp-shd sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:18 minidsp-shd sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 19:08:18 minidsp-shd sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 19:08:18 minidsp-shd systemd[1]: Started MPD Monitor Service. Dec 10 19:08:18 minidsp-shd sudo[1309]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:18 minidsp-shd volumio[893]: info: Successfully started MPD Monitor Dec 10 19:08:18 minidsp-shd mpd_monitor.sh[1314]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 19:08:18 minidsp-shd systemd[1]: Stopping MPD Monitor Service... Dec 10 19:08:18 minidsp-shd systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 10 19:08:18 minidsp-shd systemd[1]: mpd_monitor.service: Succeeded. Dec 10 19:08:18 minidsp-shd systemd[1]: Stopped MPD Monitor Service. Dec 10 19:08:18 minidsp-shd systemd[1]: Started MPD Monitor Service. Dec 10 19:08:18 minidsp-shd sudo[1311]: pam_unix(sudo:session): session closed for user root Dec 10 19:08:18 minidsp-shd mpd_monitor.sh[1316]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 19:08:18 minidsp-shd volumio[893]: info: Successfully started MPD Monitor Dec 10 19:08:18 minidsp-shd volumio[1154]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 10 19:08:19 minidsp-shd volumio[1154]: .....++++ Dec 10 19:08:19 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Dec 10 19:08:20 minidsp-shd volumio[893]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 10 19:08:21 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetQueue Dec 10 19:08:21 minidsp-shd volumio[893]: info: CoreStateMachine::getQueue Dec 10 19:08:21 minidsp-shd volumio[893]: info: CorePlayQueue::getQueue Dec 10 19:08:22 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 19:08:22 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 10 19:08:24 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 10 19:08:26 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 10 19:08:26 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 10 19:08:26 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:26 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:26 minidsp-shd volumio[893]: info: Starting MyVolumio Remote Streaming Endpoints Dec 10 19:08:26 minidsp-shd volumio[893]: info: MyVolumio not started Dec 10 19:08:26 minidsp-shd volumio[893]: info: Initializing device activation check Dec 10 19:08:26 minidsp-shd volumio[893]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 10 19:08:26 minidsp-shd volumio[893]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Dec 10 19:08:26 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 19:08:26 minidsp-shd volumio[893]: info: CURURI: music-library Dec 10 19:08:26 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:27 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 19:08:27 minidsp-shd volumio[893]: info: CURURI: music-library/NAS Dec 10 19:08:27 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:28 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 19:08:28 minidsp-shd volumio[893]: info: CURURI: music-library/NAS/Meditation Dec 10 19:08:28 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:28 minidsp-shd volumio[893]: info: Preloading song: music-library/NAS/Meditation/Die unerschoepfliche Energiequel - komplett.mp3.mp3 Dec 10 19:08:28 minidsp-shd volumio[893]: info: Exploding uri music-library/NAS/Meditation/Die unerschoepfliche Energiequel - komplett.mp3.mp3 in service mpd Dec 10 19:08:28 minidsp-shd volumio[893]: info: ALBUMART /albumart?cacheid=804&web=Nikolaus%20B.%20Enkelmann/Die%20unerschoepfliche%20Energiequelle/extralarge&path=%2Fmnt%2FNAS%2FMeditation%2FDie%20unerschoepfliche%20Energiequel%20-%20komplett.mp3.mp3&metadata=false Dec 10 19:08:28 minidsp-shd volumio[893]: info: URI /mnt/NAS/Meditation/Die unerschoepfliche Energiequel - komplett.mp3.mp3 Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 19:08:31 minidsp-shd volumio[893]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 10 19:08:31 minidsp-shd volumio[893]: info: Received Get System Version Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:31 minidsp-shd volumio[893]: info: Received Get System Info Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 19:08:31 minidsp-shd volumio[893]: info: Discovery: Getting this device information Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetState Dec 10 19:08:31 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:31 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 19:08:35 minidsp-shd volumio[893]: error: MyVolumio Plugin failed to start in a timely fashion Dec 10 19:08:35 minidsp-shd volumio[893]: [Metrics] CommandRouter: 60s 126.86ms Dec 10 19:08:35 minidsp-shd volumio[893]: info: CoreCommandRouter::volumiosetStartupVolume Dec 10 19:08:35 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:35 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:35 minidsp-shd volumio[893]: info: CoreCommandRouter::Close All Modals sent Dec 10 19:08:35 minidsp-shd volumio[893]: info: CoreCommandRouter::Close All Modals sent Dec 10 19:08:36 minidsp-shd volumio[1154]: .........................................................................................................................................................................................................................................++++ Dec 10 19:08:36 minidsp-shd volumio[1154]: e is 65537 (0x010001) Dec 10 19:08:36 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 10 19:08:36 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 19:08:36 minidsp-shd volumio[1154]: writing RSA key Dec 10 19:08:36 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 10 19:08:40 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:40 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 19:08:40 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 10 19:08:40 minidsp-shd volumio-remote-updater[580]: No test mode Dec 10 19:08:40 minidsp-shd volumio-remote-updater[580]: No alpha test mode Dec 10 19:08:40 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 10 19:08:40 minidsp-shd volumio[893]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 10 19:08:40 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 10 19:08:40 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Dec 10 19:08:41 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 19:08:41 minidsp-shd volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 10 19:08:41 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetState Dec 10 19:08:41 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 0 Dec 10 19:08:41 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 19:08:41 minidsp-shd volumio[893]: info: CURURI: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature Dec 10 19:08:42 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:42 minidsp-shd volumio[893]: info: BOOT COMPLETED Dec 10 19:08:43 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 19:08:43 minidsp-shd volumio[893]: info: CURURI: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest Dec 10 19:08:43 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:43 minidsp-shd volumio[893]: info: Preloading song: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:43 minidsp-shd volumio[893]: info: Preloading song: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/02. Evening Rain.flac Dec 10 19:08:43 minidsp-shd volumio[893]: info: Exploding uri music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac in service mpd Dec 10 19:08:43 minidsp-shd volumio[893]: info: ALBUMART /albumart?cacheid=804&web=Relaxation%20%26%20Mediation%20With%20Music%20%26%20Nature/Tropical%20Rainforest/extralarge&path=%2Fmnt%2FNAS%2FMeditation%2FRelaxation%20%26%20Mediation%20With%20Music%20%26%20Nature%2FTropical%20Rainforest%2F01.%20Exotic%20Journey.flac&metadata=false Dec 10 19:08:43 minidsp-shd volumio[893]: info: URI /mnt/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:43 minidsp-shd volumio[893]: info: Exploding uri music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/02. Evening Rain.flac in service mpd Dec 10 19:08:43 minidsp-shd volumio[893]: info: ALBUMART /albumart?cacheid=804&web=Relaxation%20%26%20Mediation%20With%20Music%20%26%20Nature/Tropical%20Rainforest/extralarge&path=%2Fmnt%2FNAS%2FMeditation%2FRelaxation%20%26%20Mediation%20With%20Music%20%26%20Nature%2FTropical%20Rainforest%2F02.%20Evening%20Rain.flac&metadata=false Dec 10 19:08:43 minidsp-shd volumio[893]: info: URI /mnt/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/02. Evening Rain.flac Dec 10 19:08:44 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddQueueItems Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::addQueueItems Dec 10 19:08:44 minidsp-shd volumio[893]: info: CorePlayQueue::addQueueItems Dec 10 19:08:44 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:44 minidsp-shd volumio[893]: info: Adding Item to queue: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:44 minidsp-shd volumio[893]: info: Using cached record of: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushQueue Dec 10 19:08:44 minidsp-shd volumio[893]: info: CorePlayQueue::saveQueue Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::updateTrackBlock Dec 10 19:08:44 minidsp-shd volumio[893]: info: CorePlayQueue::getTrackBlock Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPlay Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::play index 11 Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::stop Dec 10 19:08:44 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::play index undefined Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::startPlaybackTimer Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::clearAddPlayTracks NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand stop took 12 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand clear took 3 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" took 13 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 28ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 24ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand play took 19 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 19ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 16ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 16ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 17ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 32 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 22 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 15 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: Dec 10 19:08:45 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 36 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 18 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand status took 12 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 237ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 390ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 379ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 322 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 314 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 313 milliseconds Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:45 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:45 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:45 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:45 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 703ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 676ms Dec 10 19:08:45 minidsp-shd volumio[893]: info: ------------------------------ 672ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 19:08:46 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioAddQueueItems Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::addQueueItems Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::addQueueItems Dec 10 19:08:46 minidsp-shd volumio[893]: info: Preload queue cleared Dec 10 19:08:46 minidsp-shd volumio[893]: info: Adding Item to queue: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:46 minidsp-shd volumio[893]: info: Using cached record of: music-library/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushQueue Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::saveQueue Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::updateTrackBlock Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrackBlock Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPlay Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::play index 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::stPlaybackTimer Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::updateTrackBlock Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrackBlock Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::serviceStop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::serviceStop Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::stop Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand stop took 64 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::play index undefined Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::startPlaybackTimer Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::clearAddPlayTracks NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 16 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand stop took 5 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand clear took 18 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 14 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 11 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: No code Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: No code Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: No code Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:46 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 405ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 381ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 380ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: error: updateQueue error: null Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 392ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" took 384 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 17ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 15ms Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand play Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 142ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand play took 136 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 134ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: ------------------------------ 132ms Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 26 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 20 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 14 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: Dec 10 19:08:46 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 44 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 22 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: info: sendMpdCommand status took 15 milliseconds Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:46 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:46 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:46 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:47 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:47 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:47 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 207ms Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 346ms Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 340ms Dec 10 19:08:47 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 275 milliseconds Dec 10 19:08:47 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 266 milliseconds Dec 10 19:08:47 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 265 milliseconds Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:47 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"431 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:47 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"452 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:47 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"452 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:47 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:47 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:47 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:47 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 637ms Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 610ms Dec 10 19:08:47 minidsp-shd volumio[893]: info: ------------------------------ 604ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPlay Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::play index 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::stPlaybackTimer Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::updateTrackBlock Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrackBlock Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::serviceStop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::serviceStop Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::stop Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand stop took 46 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::play index undefined Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::startPlaybackTimer Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::clearAddPlayTracks NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 14 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand stop took 4 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand clear took 17 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 12 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 8 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: No code Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: No code Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: No code Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 367ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 345ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 343ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: error: updateQueue error: null Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 354ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand add "NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac" took 347 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 16ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 14ms Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand play Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces system playlist update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Ignoring MPD Status Update Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 140ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand play took 135 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 133ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 131ms Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 24 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 18 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 12 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: Dec 10 19:08:49 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 38 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 28 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 20 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: info: sendMpdCommand status took 15 milliseconds Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus stop Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:49 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:49 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:49 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:49 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:49 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:49 minidsp-shd volumio[893]: info: ------------------------------ 213ms Dec 10 19:08:50 minidsp-shd volumio[893]: info: ------------------------------ 341ms Dec 10 19:08:50 minidsp-shd volumio[893]: info: ------------------------------ 336ms Dec 10 19:08:50 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 278 milliseconds Dec 10 19:08:50 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 271 milliseconds Dec 10 19:08:50 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 270 milliseconds Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:50 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:50 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1006,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"440 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:50 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"442 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:50 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:50 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:50 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:50 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:50 minidsp-shd volumio[893]: info: ------------------------------ 651ms Dec 10 19:08:50 minidsp-shd volumio[893]: info: ------------------------------ 628ms Dec 10 19:08:50 minidsp-shd volumio[893]: info: ------------------------------ 623ms Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioSeek Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreStateMachine::seek Dec 10 19:08:51 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:51 minidsp-shd volumio[893]: info: TRACKBLOCK {"uri":"mnt/NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","service":"mpd","name":"1 - Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=804&web=Relaxation%20%26%20Mediation%20With%20Music%20%26%20Nature/Tropical%20Rainforest/extralarge&path=%2FNAS%2FMeditation%2FRelaxation%20%26%20Mediation%20With%20Music%20%26%20Nature%2FTropical%20Rainforest&metadata=false","duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2} Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreStateMachine::startPlaybackTimer Dec 10 19:08:51 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 19:08:51 minidsp-shd volumio[893]: info: ControllerMpd::seek Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:51 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:51 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:51 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:51 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: error: null Dec 10 19:08:52 minidsp-shd volumio[893]: info: Dec 10 19:08:52 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:52 minidsp-shd volumio[893]: info: Dec 10 19:08:52 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:52 minidsp-shd volumio[893]: info: Dec 10 19:08:52 minidsp-shd volumio[893]: ---------------------------- MPD announces state update: player Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::getState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand status Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand status took 41 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand status took 12 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand status took 6 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseState Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: ControllerMpd::parseTrackInfo Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":244000,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:52 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":245098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:52 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: ControllerMpd::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::servicePushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":245098,"duration":1831,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"Exotic Journey","artist":"Relaxation & Mediation With Music & Nature","album":"Tropical Rainforest","uri":"NAS/Meditation/Relaxation & Mediation With Music & Nature/Tropical Rainforest/01. Exotic Journey.flac","trackType":"flac"} Dec 10 19:08:52 minidsp-shd volumio[893]: verbose: CURRENT POSITION 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState stateService play Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::syncState currentStatus play Dec 10 19:08:52 minidsp-shd volumio[893]: info: Received an update from plugin. extracting info from payload Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreStateMachine::pushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: CorePlayQueue::getTrack 11 Dec 10 19:08:52 minidsp-shd volumio[893]: info: CoreCommandRouter::volumioPushState Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output update for this device Dec 10 19:08:52 minidsp-shd volumio[893]: info: MRS: Pushing multiroomSync output Dec 10 19:08:52 minidsp-shd volumio[893]: info: ------------------------------ 409ms Dec 10 19:08:52 minidsp-shd volumio[893]: info: ------------------------------ 381ms Dec 10 19:08:52 minidsp-shd volumio[893]: info: ------------------------------ 376ms Dec 10 19:09:06 minidsp-shd nmbd[714]: [2025/12/10 19:09:06.566299, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 10 19:09:06 minidsp-shd nmbd[714]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.31 for name WORKGROUP<1d>. Dec 10 19:09:06 minidsp-shd nmbd[714]: This response was from IP 192.168.0.50, reporting an IP address of 192.168.0.50. Dec 10 19:09:17 minidsp-shd volumio[893]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 10 19:09:17 minidsp-shd volumio[893]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 10 19:09:17 minidsp-shd volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 19:09:17 minidsp-shd volumio[893]: TypeError: Cannot read property 'then' of undefined Dec 10 19:09:17 minidsp-shd volumio[893]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 10 19:09:17 minidsp-shd volumio[893]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 10 19:09:17 minidsp-shd volumio[893]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 10 19:09:17 minidsp-shd volumio[893]: at Socket.emit (events.js:400:28) Dec 10 19:09:17 minidsp-shd volumio[893]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 10 19:09:17 minidsp-shd volumio[893]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 10 19:09:17 minidsp-shd volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 19:09:18 minidsp-shd sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 19:08 Dec 10 19:09:18 minidsp-shd sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:27:40 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="861d146b0ca5e28d2e157bf87e6b15ea"