-- Logs begin at Sat 2024-11-02 23:28:30 +04, end at Fri 2024-11-22 10:49:41 +04. -- Nov 22 10:48:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:28 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:28 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:28 minidsp-shd volumio[909]: verbose: New Socket.io Connection to 192.168.0.147:3000 from 192.168.0.123 UA: Dart/3.5 (dart:io) Total Clients: 6 Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:29 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:29 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:29 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:30 minidsp-shd sudo[10451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 10:48:30 minidsp-shd sudo[10451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 10:48:30 minidsp-shd sudo[10451]: pam_unix(sudo:session): session closed for user root Nov 22 10:48:30 minidsp-shd sudo[10454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 10:48:30 minidsp-shd sudo[10454]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 10:48:30 minidsp-shd sudo[10454]: pam_unix(sudo:session): session closed for user root Nov 22 10:48:30 minidsp-shd volumio[909]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Nov 22 10:48:31 minidsp-shd sudo[10457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 22 10:48:31 minidsp-shd sudo[10457]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 10:48:31 minidsp-shd sudo[10457]: pam_unix(sudo:session): session closed for user root Nov 22 10:48:31 minidsp-shd sudo[10460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 22 10:48:31 minidsp-shd sudo[10460]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 22 10:48:31 minidsp-shd sudo[10460]: pam_unix(sudo:session): session closed for user root Nov 22 10:48:31 minidsp-shd volumio[909]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetQueue Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreStateMachine::getQueue Nov 22 10:48:31 minidsp-shd volumio[909]: info: CorePlayQueue::getQueue Nov 22 10:48:31 minidsp-shd volumio[909]: info: Listing playlists Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 10:48:31 minidsp-shd volumio[909]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Nov 22 10:48:31 minidsp-shd volumio[909]: info: Received Get System Info Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:31 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 22 10:48:31 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 10:48:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Nov 22 10:48:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 22 10:48:34 minidsp-shd volumio[909]: info: Received Get System Info Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:34 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:34 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:35 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 22 10:48:35 minidsp-shd volumio[909]: info: Received Get System Info Nov 22 10:48:35 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:35 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:35 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:35 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:35 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:41 minidsp-shd volumio[909]: info: Preload queue cleared Nov 22 10:48:41 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioAddQueueItems Nov 22 10:48:41 minidsp-shd volumio[909]: info: CoreStateMachine::addQueueItems Nov 22 10:48:41 minidsp-shd volumio[909]: info: CorePlayQueue::addQueueItems Nov 22 10:48:41 minidsp-shd volumio[909]: info: Preload queue cleared Nov 22 10:48:41 minidsp-shd volumio[909]: info: Adding Item to queue: qobuz://song/744730 Nov 22 10:48:41 minidsp-shd volumio[909]: info: Exploding uri qobuz://song/744730 in service qobuz Nov 22 10:48:42 minidsp-shd volumio[909]: info: explodeUri took 1738 milliseconds Nov 22 10:48:42 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushQueue Nov 22 10:48:43 minidsp-shd volumio[909]: info: CorePlayQueue::saveQueue Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::updateTrackBlock Nov 22 10:48:43 minidsp-shd volumio[909]: info: CorePlayQueue::getTrackBlock Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPlay Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::play index 3933 Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::stop Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::play index undefined Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:48:43 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreStateMachine::startPlaybackTimer Nov 22 10:48:43 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 10:48:43 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 22 10:48:43 minidsp-shd volumio[909]: info: [1732258123722] ControllerQobuz::clearAddPlayTrack Nov 22 10:48:43 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 10:48:43 minidsp-shd volumio[909]: info: sendMpdCommand stop took 242 milliseconds Nov 22 10:48:43 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 10:48:43 minidsp-shd volumio[909]: info: Nov 22 10:48:43 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:43 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:43 minidsp-shd volumio[909]: info: sendMpdCommand clear took 25 milliseconds Nov 22 10:48:44 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/744730" Nov 22 10:48:44 minidsp-shd volumio[909]: STREAMING PROXY: Handling url /?data=qobuz://song/744730 Nov 22 10:48:44 minidsp-shd volumio[909]: info: Nov 22 10:48:44 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Nov 22 10:48:44 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Nov 22 10:48:44 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Nov 22 10:48:44 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Nov 22 10:48:44 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:44 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:44 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:44 minidsp-shd volumio[909]: info: ------------------------------ 71ms Nov 22 10:48:44 minidsp-shd volumio[909]: error: Upnp client error: Error: This socket has been ended by the other party Nov 22 10:48:44 minidsp-shd volumio[909]: info: Executing endpoint getStreamUrlqobuz Nov 22 10:48:44 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 22 10:48:45 minidsp-shd volumio[909]: info: getStreamUrl took 1345 milliseconds Nov 22 10:48:45 minidsp-shd volumio[909]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=640488&eid=744730&fmt=6&profile=raw&app_id=539451548&cid=1486318&etsp=1732261725&hmac=qd51d5gw1gxxYSEh5FVT03K4gXA Nov 22 10:48:46 minidsp-shd volumio[909]: STREAMING PROXY: Response: 200, length: 25737786 Nov 22 10:48:46 minidsp-shd volumio[909]: STREAMING PROXY: Client dropped request, destroying Nov 22 10:48:46 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/744730" Nov 22 10:48:46 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:46 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:46 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:46 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:46 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 2284ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 2278ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 2272ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 2267ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 2262ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/744730" took 14 milliseconds Nov 22 10:48:46 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 22 10:48:46 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand play Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: STREAMING PROXY: Handling url /?data=qobuz://song/744730 Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Nov 22 10:48:46 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:48:46 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 36ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: sendMpdCommand play took 27 milliseconds Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 25ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 21ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 18ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 15ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: ------------------------------ 13ms Nov 22 10:48:46 minidsp-shd volumio[909]: info: Executing endpoint getStreamUrlqobuz Nov 22 10:48:46 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 22 10:48:47 minidsp-shd volumio[909]: info: getStreamUrl took 1277 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=640488&eid=744730&fmt=6&profile=raw&app_id=539451548&cid=1486318&etsp=1732261727&hmac=OELM_UWSgMHaVCLfqYCR5W0_ZoE Nov 22 10:48:47 minidsp-shd volumio[909]: STREAMING PROXY: Response: 200, length: 25737786 Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 18 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 77 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 65 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 58 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 48 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 40 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 34 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 27 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 20 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus stop Nov 22 10:48:47 minidsp-shd volumio[909]: info: ------------------------------ 161ms Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: Nov 22 10:48:47 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 94 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 92 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 83 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 81 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 80 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 79 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 47 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 32 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 25 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: info: sendMpdCommand status took 18 milliseconds Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:47 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:47 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:47 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:47 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"910 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:47 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:47 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:47 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:47 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"919 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 560ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 553ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 532ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 524ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 518ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 512ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 505ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 363 milliseconds Nov 22 10:48:48 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 362 milliseconds Nov 22 10:48:48 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 361 milliseconds Nov 22 10:48:48 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 361 milliseconds Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"919 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"744730","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744730","trackType":"qobuz"} Nov 22 10:48:48 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3933 Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:48:48 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:48:48 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 583ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 579ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 565ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: ------------------------------ 559ms Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:48 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 22 10:48:49 minidsp-shd volumio[909]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 22 10:48:49 minidsp-shd volumio[909]: info: Received Get System Version Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 22 10:48:49 minidsp-shd volumio[909]: info: Received Get System Info Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:49 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:49 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:53 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:48:53 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:48:53 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:48:53 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:48:53 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:48:55 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:48:55 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Nov 22 10:48:56 minidsp-shd volumio[909]: info: explodeUri took 813 milliseconds Nov 22 10:48:58 minidsp-shd volumio[909]: info: handleBrowseUri took 2212 milliseconds Nov 22 10:48:59 minidsp-shd volumio[909]: info: Executing endpoint getSimilarArtists Nov 22 10:48:59 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Nov 22 10:48:59 minidsp-shd volumio[909]: info: Executing endpoint getSimilarArtists Nov 22 10:48:59 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Nov 22 10:48:59 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:48:59 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:48:59 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:48:59 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:48:59 minidsp-shd ntpd[24940]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Nov 22 10:49:01 minidsp-shd volumio[909]: error: Failed request for metavolumio API Nov 22 10:49:04 minidsp-shd volumio[909]: STREAMING PROXY: Client dropped request, destroying Nov 22 10:49:17 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:17 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:17 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:49:17 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto Nov 22 10:49:18 minidsp-shd volumio[909]: info: explodeUri took 553 milliseconds Nov 22 10:49:20 minidsp-shd volumio[909]: info: handleBrowseUri took 1649 milliseconds Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:20 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:20 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:21 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:21 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:21 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:21 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:22 minidsp-shd volumio[909]: info: Executing endpoint metavolumio Nov 22 10:49:22 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 22 10:49:23 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 10:49:23 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 10:49:23 minidsp-shd volumio[909]: info: Discovery: Getting this device information Nov 22 10:49:23 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioGetState Nov 22 10:49:23 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 10:49:26 minidsp-shd volumio[909]: info: Preload queue cleared Nov 22 10:49:26 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioAddQueueItems Nov 22 10:49:26 minidsp-shd volumio[909]: info: CoreStateMachine::addQueueItems Nov 22 10:49:26 minidsp-shd volumio[909]: info: CorePlayQueue::addQueueItems Nov 22 10:49:26 minidsp-shd volumio[909]: info: Preload queue cleared Nov 22 10:49:26 minidsp-shd volumio[909]: info: Adding Item to queue: qobuz://album/0060251783509 Nov 22 10:49:26 minidsp-shd volumio[909]: info: Exploding uri qobuz://album/0060251783509 in service qobuz Nov 22 10:49:27 minidsp-shd volumio[909]: info: explodeUri took 545 milliseconds Nov 22 10:49:27 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushQueue Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::saveQueue Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::updateTrackBlock Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrackBlock Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPlay Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::play index 3934 Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::stop Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::stPlaybackTimer Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::updateTrackBlock Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrackBlock Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:28 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::serviceStop Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3933 Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::serviceStop Nov 22 10:49:28 minidsp-shd volumio[909]: info: [1732258168112] ControllerQobuz::stop Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::stop Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand stop took 224 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::play index undefined Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::startPlaybackTimer Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:28 minidsp-shd volumio[909]: info: [1732258168349] ControllerQobuz::clearAddPlayTrack Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 45 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand stop took 38 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 35 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 31 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 29 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 26 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand status took 24 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand clear took 37 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 35 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 33 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/744729" Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:28 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:28 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:28 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:28 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:28 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:28 minidsp-shd volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Nov 22 10:49:28 minidsp-shd volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 10:49:28 minidsp-shd volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 10:49:28 minidsp-shd volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 10:49:28 minidsp-shd volumio[909]: STREAMING PROXY: Handling url /?data=qobuz://song/744729 Nov 22 10:49:28 minidsp-shd volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 10:49:28 minidsp-shd volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 202ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 188ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 184ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 180ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 177ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 173ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Nov 22 10:49:28 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:28 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:28 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:28 minidsp-shd volumio[909]: info: ------------------------------ 134ms Nov 22 10:49:28 minidsp-shd volumio[909]: info: Executing endpoint getStreamUrlqobuz Nov 22 10:49:28 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 22 10:49:29 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:29 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:29 minidsp-shd volumio[909]: info: getStreamUrl took 1304 milliseconds Nov 22 10:49:29 minidsp-shd volumio[909]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=640488&eid=744729&fmt=6&profile=raw&app_id=539451548&cid=1486318&etsp=1732261769&hmac=xRAmqQMJnB4jyv2QAn5wZBgKb08 Nov 22 10:49:30 minidsp-shd volumio[909]: STREAMING PROXY: Response: 200, length: 30886469 Nov 22 10:49:30 minidsp-shd volumio[909]: STREAMING PROXY: Client dropped request, destroying Nov 22 10:49:30 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/744729" Nov 22 10:49:30 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:30 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:30 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:30 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:30 minidsp-shd volumio[909]: error: updateQueue error: null Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 2330ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 2327ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 2325ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 2323ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 2321ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/744729" took 14 milliseconds Nov 22 10:49:30 minidsp-shd volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 22 10:49:30 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand play Nov 22 10:49:30 minidsp-shd volumio[909]: STREAMING PROXY: Handling url /?data=qobuz://song/744729 Nov 22 10:49:30 minidsp-shd volumio[909]: Upnp client error: Error: This socket has been ended by the other party Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Nov 22 10:49:30 minidsp-shd volumio[909]: ---------------------------- MPD announces system playlist update Nov 22 10:49:30 minidsp-shd volumio[909]: info: Ignoring MPD Status Update Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 39ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: sendMpdCommand play took 31 milliseconds Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 23ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 19ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 16ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 14ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: ------------------------------ 11ms Nov 22 10:49:30 minidsp-shd volumio[909]: info: Executing endpoint getStreamUrlqobuz Nov 22 10:49:30 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 22 10:49:32 minidsp-shd volumio[909]: info: getStreamUrl took 1232 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=640488&eid=744729&fmt=6&profile=raw&app_id=539451548&cid=1486318&etsp=1732261771&hmac=kGQTgaihBSv5lYQjc5Rhn5YQAXg Nov 22 10:49:32 minidsp-shd volumio[909]: STREAMING PROXY: Response: 200, length: 30886469 Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 18 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 95 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 80 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 68 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 58 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 50 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 40 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 27 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"662 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus stop Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 211ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: Nov 22 10:49:32 minidsp-shd volumio[909]: ---------------------------- MPD announces state update: player Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::getState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand status Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 90 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 86 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 86 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 85 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 83 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 55 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 48 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 41 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 35 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand status took 30 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseState Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"765 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 539ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 509ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 496ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 486ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 478ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: ------------------------------ 466ms Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 299 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 297 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 295 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 293 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: info: sendMpdCommand playlistinfo took 290 milliseconds Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: ControllerMpd::parseTrackInfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: ControllerMpd::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::servicePushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CorePlayQueue::getTrack 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":301,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"744729","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/744729","trackType":"qobuz"} Nov 22 10:49:32 minidsp-shd volumio[909]: verbose: CURRENT POSITION 3934 Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState stateService play Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::syncState currentStatus play Nov 22 10:49:32 minidsp-shd volumio[909]: info: Received an update from plugin. extracting info from payload Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreStateMachine::pushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: CoreCommandRouter::volumioPushState Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output update for this device Nov 22 10:49:32 minidsp-shd volumio[909]: info: MRS: Pushing multiroomSync output Nov 22 10:49:33 minidsp-shd volumio[909]: info: ------------------------------ 593ms Nov 22 10:49:33 minidsp-shd volumio[909]: info: ------------------------------ 586ms Nov 22 10:49:33 minidsp-shd volumio[909]: info: ------------------------------ 580ms Nov 22 10:49:33 minidsp-shd volumio[909]: info: ------------------------------ 575ms Nov 22 10:49:33 minidsp-shd volumio[909]: info: ------------------------------ 577ms Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:33 minidsp-shd volumio[909]: info: Ignoring ROON Volume update because of undefined data Nov 22 10:49:39 minidsp-shd volumio[909]: Searching all installed plugins Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 22 10:49:39 minidsp-shd volumio[909]: info: [1732258179935] ControllerCalmRadio::searchCategories Nov 22 10:49:39 minidsp-shd volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 10:49:39 minidsp-shd volumio[909]: TypeError: Cannot read property 'cats' of undefined Nov 22 10:49:39 minidsp-shd volumio[909]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 22 10:49:39 minidsp-shd volumio[909]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 22 10:49:39 minidsp-shd volumio[909]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Nov 22 10:49:39 minidsp-shd volumio[909]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 22 10:49:39 minidsp-shd volumio[909]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 22 10:49:39 minidsp-shd volumio[909]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Nov 22 10:49:39 minidsp-shd volumio[909]: at Socket.emit (events.js:400:28) Nov 22 10:49:39 minidsp-shd volumio[909]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 22 10:49:39 minidsp-shd volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 22 10:49:39 minidsp-shd volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 10:49:41 minidsp-shd sudo[10627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 10:48 Nov 22 10:49:41 minidsp-shd sudo[10627]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"