-- Logs begin at Tue 2025-07-08 11:34:16 WIB, end at Tue 2025-07-08 13:48:21 WIB. -- Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 13:47:00 primo volumio[3204]: info: Received Get System Info Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:47:00 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:00 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 13:47:00 primo volumio[3204]: info: Received Get System Info Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:47:00 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:00 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:00 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:47:03 primo go-librespot[4794]: time="2025-07-08T13:47:03+07:00" level=trace msg="sent dealer ping" Jul 08 13:47:03 primo go-librespot[4794]: time="2025-07-08T13:47:03+07:00" level=trace msg="received dealer pong" Jul 08 13:47:04 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 13:47:14 primo volumio[3204]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 08 13:47:14 primo volumio[3204]: info: Received Get System Version Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 13:47:14 primo volumio[3204]: info: Received Get System Info Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:47:14 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:14 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:14 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:47:21 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:47:21 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:47:21 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:47:21 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:21 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:21 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jul 08 13:47:27 primo volumio[3204]: info: Received Get System Version Jul 08 13:47:27 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 13:47:33 primo go-librespot[4794]: time="2025-07-08T13:47:33+07:00" level=trace msg="sent dealer ping" Jul 08 13:47:33 primo ntpd[3372]: 185.125.190.58 local addr 192.168.1.103 -> Jul 08 13:47:33 primo go-librespot[4794]: time="2025-07-08T13:47:33+07:00" level=trace msg="received dealer pong" Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::startPlaybackTimer Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand playlistinfo took 120946 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:47 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 120977ms Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand stop took 46 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand status took 20 milliseconds Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand status took 10 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand status took 8 milliseconds Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:47 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:47 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:47 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:47 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:47 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:47 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:47 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:47 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 57ms Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 56ms Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: Preload queue cleared Jul 08 13:47:47 primo volumio[3204]: info: Preloading song: music-library/33 Jul 08 13:47:47 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand clear took 64 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand playlistinfo took 64 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 62ms Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:47 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 95ms Jul 08 13:47:47 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:47 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:47 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:47 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:47 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 36ms Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:47 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 39ms Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" took 34 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 6ms Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Jul 08 13:47:47 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 8ms Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: Exploding uri music-library/33 in service mpd Jul 08 13:47:47 primo volumio[3204]: error: scanFolder - failure to stat '/mnt/33' Jul 08 13:47:47 primo volumio[3204]: info: Jul 08 13:47:47 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:47 primo volumio[3204]: info: ------------------------------ 39ms Jul 08 13:47:47 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand play took 37 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand status took 31 milliseconds Jul 08 13:47:47 primo volumio[3204]: info: sendMpdCommand status took 31 milliseconds Jul 08 13:47:47 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:48 primo volumio[3204]: info: Jul 08 13:47:48 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:48 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:48 primo volumio[3204]: info: Jul 08 13:47:48 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:48 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:48 primo volumio[3204]: info: sendMpdCommand status took 36 milliseconds Jul 08 13:47:48 primo volumio[3204]: info: sendMpdCommand status took 11 milliseconds Jul 08 13:47:48 primo volumio[3204]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 13:47:48 primo volumio[3204]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:48 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:48 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:48 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:48 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:48 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:48 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:48 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:48 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:48 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:48 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:48 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:48 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:48 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:48 primo volumio[3204]: info: ------------------------------ 82ms Jul 08 13:47:48 primo volumio[3204]: info: ------------------------------ 58ms Jul 08 13:47:48 primo volumio[3204]: info: ------------------------------ 86ms Jul 08 13:47:48 primo volumio[3204]: info: ------------------------------ 85ms Jul 08 13:47:48 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:48 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:48 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:48 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:48 primo volumio[3204]: info: MCU Signalled Playback Inactive Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::startPlaybackTimer Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 1302 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 1322ms Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 1319 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand stop took 67 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 36 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:49 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 1342ms Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 19 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 18 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 69ms Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 69ms Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand clear took 60 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 58ms Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:49 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 103ms Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:49 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 30ms Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:49 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 36ms Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" took 33 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 10ms Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Jul 08 13:47:49 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 11ms Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 35ms Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand play took 31 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 28 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: Jul 08 13:47:49 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 43 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 42 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 71ms Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 50 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 37 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 99ms Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 68ms Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:49 primo volumio[3204]: info: sendMpdCommand status took 100 milliseconds Jul 08 13:47:49 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:49 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:49 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:49 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:49 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:49 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:49 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:49 primo volumio[3204]: info: ------------------------------ 143ms Jul 08 13:47:49 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:49 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::startPlaybackTimer Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand playlistinfo took 1094 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:50 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 1142ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand playlistinfo took 1070 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand stop took 18 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 9 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:50 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 1146ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 26 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 25 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:50 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:50 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 61ms Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 61ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand clear took 59 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand playlistinfo took 58 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 44ms Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:50 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 74ms Jul 08 13:47:50 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:50 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:50 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:50 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:50 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 39ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:50 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 43ms Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" took 39 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 8ms Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Jul 08 13:47:50 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 8ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 21ms Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand play took 20 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 15 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: Jul 08 13:47:50 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 22 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 20 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 10 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:50 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:50 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 56ms Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 60ms Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 41 milliseconds Jul 08 13:47:50 primo volumio[3204]: info: sendMpdCommand status took 40 milliseconds Jul 08 13:47:50 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:50 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:50 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:50 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:50 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:50 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:50 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:50 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 88ms Jul 08 13:47:50 primo volumio[3204]: info: ------------------------------ 87ms Jul 08 13:47:50 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:50 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:50 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:50 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:50 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:50 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::startPlaybackTimer Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:47:51 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand playlistinfo took 1041 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:51 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 1067ms Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand playlistinfo took 1042 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:51 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 1058ms Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand stop took 34 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 8 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 6 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 4 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:51 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:51 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 41ms Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 40ms Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand clear took 43 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 42ms Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:51 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 57ms Jul 08 13:47:51 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:51 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:51 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:51 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:51 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 32ms Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:51 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 34ms Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/04. Solo Repeat!.flac" took 31 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 5ms Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Jul 08 13:47:51 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 9ms Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 16ms Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand play took 15 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 9 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: Jul 08 13:47:51 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 14 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 11 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 8 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:51 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:51 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 43ms Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 49ms Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 37 milliseconds Jul 08 13:47:51 primo volumio[3204]: info: sendMpdCommand status took 36 milliseconds Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:51 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:51 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:51 primo volumio[3204]: verbose: CURRENT POSITION 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:51 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:51 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:51 primo volumio[3204]: info: CorePlayQueue::getTrack 3 Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:51 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 80ms Jul 08 13:47:51 primo volumio[3204]: info: ------------------------------ 79ms Jul 08 13:47:51 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:51 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:51 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:51 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:51 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:51 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioNext Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::next Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::startPlaybackTimer Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/05. Kandace Springs.flac Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::updateTrackBlock Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrackBlock Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand playlistinfo took 434 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:52 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 455ms Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand playlistinfo took 442 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand stop took 17 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 13:47:52 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 456ms Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 11 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 9 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:52 primo volumio[3204]: verbose: CURRENT POSITION 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 39ms Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 43 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand clear took 39 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 38ms Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/05. Kandace Springs.flac" Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:52 primo volumio[3204]: verbose: CURRENT POSITION 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:52 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 78ms Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 85ms Jul 08 13:47:52 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:52 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:52 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:52 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:52 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 70ms Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces system playlist update Jul 08 13:47:52 primo volumio[3204]: info: Ignoring MPD Status Update Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 90ms Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand add "USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/05. Kandace Springs.flac" took 85 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 25ms Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Jul 08 13:47:52 primo volumio[3204]: error: updateQueue error: null Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 25ms Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 103ms Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand play took 96 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 90 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 17 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 15 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:52 primo volumio[3204]: info: Jul 08 13:47:52 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 15 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand status took 13 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 08 13:47:52 primo volumio[3204]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:52 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:52 primo volumio[3204]: verbose: CURRENT POSITION 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:52 primo volumio[3204]: verbose: CURRENT POSITION 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:52 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:52 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:52 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:52 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:52 primo volumio[3204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":349,"duration":200,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"9229 Kbps","isStreaming":false,"title":"Kandace Springs","artist":"Breakdown","album":"Reference Check Vol. 2 | 50 Years Canton [UHQCD]","uri":"USB/ECE6-15D8/CD TEST(Official)/Canton 50 Years . Reference Check Vol. 2 [UHQCD]/05. Kandace Springs.flac","trackType":"flac"} Jul 08 13:47:52 primo volumio[3204]: verbose: CURRENT POSITION 4 Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState stateService play Jul 08 13:47:52 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:52 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 81ms Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 76ms Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 164ms Jul 08 13:47:52 primo volumio[3204]: info: ------------------------------ 88ms Jul 08 13:47:52 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:52 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:52 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:52 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:54 primo volumio[3204]: info: Executing endpoint metavolumio Jul 08 13:47:54 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 13:47:54 primo volumio[3204]: info: Executing endpoint metavolumio Jul 08 13:47:54 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 13:47:54 primo volumio[3204]: info: Executing endpoint metavolumio Jul 08 13:47:54 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 13:47:56 primo volumio[3204]: info: Preload queue cleared Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::ClearQueue Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::stop Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::stPlaybackTimer Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::updateTrackBlock Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrackBlock Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::serviceStop Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 4 Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::serviceStop Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::stop Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::clearPlayQueue Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::saveQueue Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPushQueue Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::addQueueItems Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::addQueueItems Jul 08 13:47:56 primo volumio[3204]: info: Preload queue cleared Jul 08 13:47:56 primo volumio[3204]: info: Adding Item to queue: music-library/33 Jul 08 13:47:56 primo volumio[3204]: info: Using cached record of: music-library/33 Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPushQueue Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::saveQueue Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::updateTrackBlock Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrackBlock Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::play index 0 Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::stop Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:56 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand playlistinfo took 4367 milliseconds Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:47:56 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:47:56 primo volumio[3204]: info: ------------------------------ 4388ms Jul 08 13:47:56 primo volumio[3204]: info: Jul 08 13:47:56 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:56 primo volumio[3204]: info: Jul 08 13:47:56 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:56 primo volumio[3204]: info: Jul 08 13:47:56 primo volumio[3204]: ---------------------------- MPD announces state update: player Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand status took 4377 milliseconds Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand stop took 65 milliseconds Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand status took 10 milliseconds Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand status took 11 milliseconds Jul 08 13:47:56 primo volumio[3204]: info: sendMpdCommand status took 6 milliseconds Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:56 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:56 primo volumio[3204]: verbose: CURRENT POSITION 0 Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:56 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:47:56 primo volumio[3204]: verbose: CURRENT POSITION 0 Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:47:56 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:47:56 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:47:56 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:47:56 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:47:56 primo volumio[3204]: info: ------------------------------ 49ms Jul 08 13:47:56 primo volumio[3204]: info: ------------------------------ 44ms Jul 08 13:47:56 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:47:56 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:47:57 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:48:02 primo go-librespot[4794]: time="2025-07-08T13:48:02+07:00" level=trace msg="received accesspoint ping" Jul 08 13:48:02 primo go-librespot[4794]: time="2025-07-08T13:48:02+07:00" level=trace msg="received accesspoint pong ack" Jul 08 13:48:03 primo go-librespot[4794]: time="2025-07-08T13:48:03+07:00" level=trace msg="sent dealer ping" Jul 08 13:48:03 primo go-librespot[4794]: time="2025-07-08T13:48:03+07:00" level=trace msg="received dealer pong" Jul 08 13:48:04 primo volumio[3204]: info: Preload queue cleared Jul 08 13:48:04 primo volumio[3204]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::ClearQueue Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::stop Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::clearPlayQueue Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::saveQueue Jul 08 13:48:04 primo volumio[3204]: info: CoreCommandRouter::volumioPushQueue Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::addQueueItems Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::addQueueItems Jul 08 13:48:04 primo volumio[3204]: info: Preload queue cleared Jul 08 13:48:04 primo volumio[3204]: info: Adding Item to queue: music-library/33 Jul 08 13:48:04 primo volumio[3204]: info: Using cached record of: music-library/33 Jul 08 13:48:04 primo volumio[3204]: info: CoreCommandRouter::volumioPushQueue Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::saveQueue Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::updateTrackBlock Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::getTrackBlock Jul 08 13:48:04 primo volumio[3204]: info: CoreCommandRouter::volumioPlay Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::play index 0 Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::stop Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::play index undefined Jul 08 13:48:04 primo volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 13:48:04 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:06 primo sudo[6726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 13:48:06 primo sudo[6726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 13:48:06 primo sudo[6726]: pam_unix(sudo:session): session closed for user root Jul 08 13:48:06 primo sudo[6730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 13:48:06 primo sudo[6730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 13:48:06 primo sudo[6730]: pam_unix(sudo:session): session closed for user root Jul 08 13:48:06 primo volumio[3204]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.105 UA: Mozilla/5.0 (Linux; Android 15; SM-F926B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 08 13:48:06 primo sudo[6734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 13:48:06 primo sudo[6734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 13:48:06 primo sudo[6734]: pam_unix(sudo:session): session closed for user root Jul 08 13:48:06 primo sudo[6737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 13:48:06 primo sudo[6737]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 13:48:06 primo sudo[6737]: pam_unix(sudo:session): session closed for user root Jul 08 13:48:06 primo volumio[3204]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.105 UA: Mozilla/5.0 (Linux; Android 15; SM-F926B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.45 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:06 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::volumioGetQueue Jul 08 13:48:06 primo volumio[3204]: info: CoreStateMachine::getQueue Jul 08 13:48:06 primo volumio[3204]: info: CorePlayQueue::getQueue Jul 08 13:48:06 primo volumio[3204]: info: Listing playlists Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 13:48:06 primo volumio[3204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 08 13:48:06 primo volumio[3204]: info: Received Get System Info Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:48:06 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:06 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:06 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 13:48:06 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 13:48:08 primo volumio[3204]: info: Received Get System Info Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:48:08 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:08 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:48:08 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 08 13:48:08 primo volumio[3204]: info: CURURI: music-library Jul 08 13:48:08 primo volumio[3204]: info: sendMpdCommand playlistinfo took 11644 milliseconds Jul 08 13:48:08 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:48:08 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:48:08 primo volumio[3204]: info: ------------------------------ 16025ms Jul 08 13:48:09 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 13:48:09 primo volumio[3204]: info: Received Get System Info Jul 08 13:48:09 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 13:48:09 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 13:48:09 primo volumio[3204]: info: Discovery: Getting this device information Jul 08 13:48:09 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:09 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:09 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: Retrieving Cloud Streaming UI Jul 08 13:48:15 primo volumio[3204]: info: Getting Tidal Cloud Configuration Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: Getting Qobuz Cloud Configuration Jul 08 13:48:15 primo volumio[3204]: info: Asking plugin for UI Config Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: Getting Spotify Cloud Configuration Jul 08 13:48:15 primo volumio[3204]: info: Asking plugin for UI Config Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: Saving Spotify Acccount Jul 08 13:48:15 primo volumio[3204]: info: Got it Jul 08 13:48:15 primo volumio[3204]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 08 13:48:15 primo volumio[3204]: info: Got Tidal Cloud Configuration Jul 08 13:48:15 primo volumio[3204]: info: Got it Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 13:48:15 primo volumio[3204]: info: sendMpdCommand playlistinfo took 18911 milliseconds Jul 08 13:48:15 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:48:15 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:48:15 primo volumio[3204]: info: ------------------------------ 18924ms Jul 08 13:48:15 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 08 13:48:16 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand rescan Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: info: Jul 08 13:48:17 primo volumio[3204]: ---------------------------- MPD announces state update: update Jul 08 13:48:17 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand rescan took 12 milliseconds Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: info: Jul 08 13:48:17 primo volumio[3204]: ---------------------------- MPD announces state update: update Jul 08 13:48:17 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: info: Jul 08 13:48:17 primo volumio[3204]: ---------------------------- MPD announces state update: update Jul 08 13:48:17 primo volumio[3204]: info: ControllerMpd::getState Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 20 milliseconds Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: info: Command Router : Notfying DB Updatefalse Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::Close All Modals sent Jul 08 13:48:17 primo volumio[3204]: info: Preload queue cleared Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 82 milliseconds Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 78 milliseconds Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 77 milliseconds Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: info: Command Router : Notfying DB Updatetrue Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 153 milliseconds Jul 08 13:48:17 primo volumio[3204]: info: sendMpdCommand status took 152 milliseconds Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: info: Command Router : Notfying DB Updatefalse Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::Close All Modals sent Jul 08 13:48:17 primo volumio[3204]: verbose: ControllerMpd::parseState Jul 08 13:48:17 primo volumio[3204]: info: ControllerMpd::pushState Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::servicePushState Jul 08 13:48:17 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:17 primo volumio[3204]: verbose: STATE SERVICE {"status":null,"position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":null,"updatedb":false,"repeat":null,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 13:48:17 primo volumio[3204]: verbose: CURRENT POSITION 0 Jul 08 13:48:17 primo volumio[3204]: info: CoreStateMachine::syncState stateService null Jul 08 13:48:17 primo volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Jul 08 13:48:17 primo volumio[3204]: info: CoreStateMachine::pushState Jul 08 13:48:17 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::volumioPushState Jul 08 13:48:17 primo volumio[3204]: info: CoreCommandRouter::volumioGetState Jul 08 13:48:17 primo volumio[3204]: info: CorePlayQueue::getTrack 0 Jul 08 13:48:17 primo volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Jul 08 13:48:17 primo volumio[3204]: info: MRS: Pushing multiroomSync output Jul 08 13:48:17 primo volumio[3204]: info: ------------------------------ 226ms Jul 08 13:48:17 primo volumio[3204]: info: Updating RAAT Signal Path Jul 08 13:48:17 primo volumio[3204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 08 13:48:18 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 08 13:48:18 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand rescan Jul 08 13:48:18 primo volumio[3204]: info: sendMpdCommand playlistinfo took 1506 milliseconds Jul 08 13:48:18 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:48:18 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:48:18 primo volumio[3204]: info: ------------------------------ 1595ms Jul 08 13:48:19 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 13:48:19 primo volumio[3204]: info: sendMpdCommand playlistinfo took 2424 milliseconds Jul 08 13:48:19 primo volumio[3204]: verbose: ControllerMpd::parseTrackInfo Jul 08 13:48:19 primo volumio[3204]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 13:48:19 primo volumio[3204]: info: ------------------------------ 2554ms Jul 08 13:48:19 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 08 13:48:19 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand rescan Jul 08 13:48:19 primo volumio[3204]: info: sendMpdCommand rescan took 1006 milliseconds Jul 08 13:48:20 primo volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 08 13:48:20 primo volumio[3204]: verbose: ControllerMpd::sendMpdCommand rescan Jul 08 13:48:20 primo volumio[3204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 13:48:20 primo volumio[3204]: TypeError: Cannot read property 'substring' of undefined Jul 08 13:48:20 primo volumio[3204]: at /volumio/app/plugins/music_service/mpd/index.js:2827:70 Jul 08 13:48:20 primo volumio[3204]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 08 13:48:20 primo volumio[3204]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 08 13:48:20 primo volumio[3204]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 08 13:48:20 primo volumio[3204]: at Socket.emit (events.js:400:28) Jul 08 13:48:20 primo volumio[3204]: at addChunk (internal/streams/readable.js:293:12) Jul 08 13:48:20 primo volumio[3204]: at readableAddChunk (internal/streams/readable.js:263:11) Jul 08 13:48:20 primo volumio[3204]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jul 08 13:48:20 primo volumio[3204]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 08 13:48:20 primo volumio[3204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 13:48:21 primo sudo[6779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 13:47 Jul 08 13:48:21 primo sudo[6779]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 05:35:39 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="edafce61cd208ac365b59b7b2fb693e3"