-- Logs begin at Wed 2026-02-18 11:49:02 +07, end at Wed 2026-02-18 12:47:52 +07. -- Feb 18 12:46:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:46:01+07:00" level=trace msg="sent dealer ping" Feb 18 12:46:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:46:01+07:00" level=trace msg="received dealer pong" Feb 18 12:46:15 volumio-chen1 volumio[1101]: verbose: New Socket.io Connection to 192.168.1.61:3000 from 192.168.1.33 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: Discovery: Getting this device information Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetState Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:15 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 12:46:18 volumio-chen1 volumio[1101]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetState Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: Received Get System Info Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: Discovery: Getting this device information Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetState Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetState Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: Listing playlists Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetQueue Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreStateMachine::getQueue Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CorePlayQueue::getQueue Feb 18 12:46:18 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: Received Get System Info Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: Discovery: Getting this device information Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioGetState Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:20 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 12:46:21 volumio-chen1 thd[614]: {"time":1771393438127,"response":"toggle Success"}Executing trigger action: /usr/local/bin/volumio next Feb 18 12:46:21 volumio-chen1 thd[614]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 12:46:21 volumio-chen1 thd[614]: Dload Upload Total Spent Left Speed Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- Client requests Volumio next Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioNext Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::next Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:46:21 volumio-chen1 thd[614]: [158B blob data] Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 16 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/AR test/06 Track 06.aif Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 3 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 3 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 2 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 12 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/AR test/06 Track 06.aif" Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 05","artist":null,"album":null,"uri":"USB/New_Volume/AR test/05 Track 05.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: No code Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:46:21 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 68ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 65ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 64ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 64ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/AR test/06 Track 06.aif" took 55 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 4ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 3ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 19ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 17 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 17ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 15ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 7 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Feb 18 12:46:21 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 7 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 55ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 115ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 114ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 114ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 93 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 93 milliseconds Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":277,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:21 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 163ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: info: ------------------------------ 162ms Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:21 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:31 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:46:31+07:00" level=trace msg="sent dealer ping" Feb 18 12:46:31 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:46:31+07:00" level=trace msg="received dealer pong" Feb 18 12:46:49 volumio-chen1 thd[614]: {"time":1771393581458,"response":"next Success"}Executing trigger action: /usr/local/bin/volumio next Feb 18 12:46:49 volumio-chen1 thd[614]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 12:46:49 volumio-chen1 thd[614]: Dload Upload Total Spent Left Speed Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- Client requests Volumio next Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioNext Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::next Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:46:49 volumio-chen1 thd[614]: [158B blob data] Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 26 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/AR test/07 Track 07.aif Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 4 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 2 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 1 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 3 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/AR test/07 Track 07.aif" Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 06","artist":null,"album":null,"uri":"USB/New_Volume/AR test/06 Track 06.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: No code Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:46:49 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 58ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 55ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 54ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 55ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/AR test/07 Track 07.aif" took 53 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 4ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 3ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 13ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 12 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 13ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 12ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 17ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Feb 18 12:46:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 79 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 183ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 182ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 181ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 180ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 93 milliseconds Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":966,"duration":295,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 07","artist":null,"album":null,"uri":"USB/New_Volume/AR test/07 Track 07.aif","trackType":"aif"} Feb 18 12:46:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:46:49 volumio-chen1 volumio[1101]: info: ------------------------------ 193ms Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:46:56 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 12:46:56 volumio-chen1 volumio[1101]: info: CURURI: music-library/USB/New_Volume/Audiophile Collection Feb 18 12:46:56 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:01+07:00" level=trace msg="received accesspoint ping" Feb 18 12:47:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:01+07:00" level=trace msg="received accesspoint pong ack" Feb 18 12:47:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:01+07:00" level=trace msg="sent dealer ping" Feb 18 12:47:01 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:01+07:00" level=trace msg="received dealer pong" Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: CURURI: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: CURURI: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/05 audio hi end test usher - Track 5.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/06 audio hi end test usher - Track 6.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/07 audio hi end test usher - Track 7.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/08 audio hi end test usher - Track 8.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/09 audio hi end test usher - Track 9.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/10 audio hi end test usher - Track 10.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/11 audio hi end test usher - Track 11.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/13 audio hi end test usher - Track 13.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/14 audio hi end test usher - Track 14.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/15 audio hi end test usher - Track 15.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/16 audio hi end test usher - Track 16.wav Feb 18 12:47:02 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/17 audio hi end test usher - Track 17.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F01%20audio%20hi%20end%20test%20usher%20-%20Track%201.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F02%20audio%20hi%20end%20test%20usher%20-%20Track%202.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F03%20audio%20hi%20end%20test%20usher%20-%20Track%203.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F04%20audio%20hi%20end%20test%20usher%20-%20Track%204.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/05 audio hi end test usher - Track 5.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F05%20audio%20hi%20end%20test%20usher%20-%20Track%205.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/05 audio hi end test usher - Track 5.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/06 audio hi end test usher - Track 6.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F06%20audio%20hi%20end%20test%20usher%20-%20Track%206.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/06 audio hi end test usher - Track 6.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/07 audio hi end test usher - Track 7.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F07%20audio%20hi%20end%20test%20usher%20-%20Track%207.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/07 audio hi end test usher - Track 7.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/08 audio hi end test usher - Track 8.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F08%20audio%20hi%20end%20test%20usher%20-%20Track%208.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/08 audio hi end test usher - Track 8.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/09 audio hi end test usher - Track 9.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F09%20audio%20hi%20end%20test%20usher%20-%20Track%209.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/09 audio hi end test usher - Track 9.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/10 audio hi end test usher - Track 10.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F10%20audio%20hi%20end%20test%20usher%20-%20Track%2010.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/10 audio hi end test usher - Track 10.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/11 audio hi end test usher - Track 11.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F11%20audio%20hi%20end%20test%20usher%20-%20Track%2011.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/11 audio hi end test usher - Track 11.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::ClearQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::clearPlayQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::addQueueItems Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::addQueueItems Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPlay Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::addQueueItems Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::addQueueItems Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/05 audio hi end test usher - Track 5.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/05 audio hi end test usher - Track 5.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/06 audio hi end test usher - Track 6.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/06 audio hi end test usher - Track 6.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/07 audio hi end test usher - Track 7.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/07 audio hi end test usher - Track 7.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/08 audio hi end test usher - Track 8.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/08 audio hi end test usher - Track 8.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/09 audio hi end test usher - Track 9.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/09 audio hi end test usher - Track 9.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/10 audio hi end test usher - Track 10.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/10 audio hi end test usher - Track 10.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/11 audio hi end test usher - Track 11.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/11 audio hi end test usher - Track 11.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/13 audio hi end test usher - Track 13.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/13 audio hi end test usher - Track 13.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/14 audio hi end test usher - Track 14.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/14 audio hi end test usher - Track 14.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/15 audio hi end test usher - Track 15.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/15 audio hi end test usher - Track 15.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/16 audio hi end test usher - Track 16.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/16 audio hi end test usher - Track 16.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/17 audio hi end test usher - Track 17.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/17 audio hi end test usher - Track 17.wav in service mpd Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F12%20audio%20hi%20end%20test%20usher%20-%20Track%2012.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F12%20audio%20hi%20end%20test%20usher%20-%20Track%2012.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/12 audio hi end test usher - Track 12.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F13%20audio%20hi%20end%20test%20usher%20-%20Track%2013.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/13 audio hi end test usher - Track 13.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F14%20audio%20hi%20end%20test%20usher%20-%20Track%2014.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/14 audio hi end test usher - Track 14.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F15%20audio%20hi%20end%20test%20usher%20-%20Track%2015.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/15 audio hi end test usher - Track 15.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F16%20audio%20hi%20end%20test%20usher%20-%20Track%2016.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/16 audio hi end test usher - Track 16.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=audio%20hi%20end%20test%20usher//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2Faudio%20hi%20end%20test%20usher%2Faudio%2F17%20audio%20hi%20end%20test%20usher%20-%20Track%2017.wav&metadata=false Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/17 audio hi end test usher - Track 17.wav Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 65 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 14 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 12 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 11 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav" Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 12ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav" took 10 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 55ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 71ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 70ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 78ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 73 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 12ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 13ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Feb 18 12:47:03 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 30 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 29 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 25 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 22 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav","trackType":"wav"} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav","trackType":"wav"} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav","trackType":"wav"} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav","trackType":"wav"} Feb 18 12:47:03 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 89ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 139ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 132ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: info: ------------------------------ 128ms Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:03 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 thd[614]: {"time":1771393609116,"response":"next Success"}Executing trigger action: /usr/local/bin/volumio next Feb 18 12:47:08 volumio-chen1 thd[614]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 12:47:08 volumio-chen1 thd[614]: Dload Upload Total Spent Left Speed Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- Client requests Volumio next Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioNext Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::next Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:08 volumio-chen1 thd[614]: [158B blob data] Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 15 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 8 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 3 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav" Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/01 audio hi end test usher - Track 1.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:08 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 66ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 55ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 53ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 56ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav" took 53 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 11ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 9ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 33ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 29 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 28ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 22ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 70 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 69 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 67 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Feb 18 12:47:08 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 90ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 130ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 129ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 56 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 55 milliseconds Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:08 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 95ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 93ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: info: ------------------------------ 92ms Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:08 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: CURURI: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/02-Andrea Bocelli _ Time To Say Goodbye (duet with.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/03-Parov Stelar _ All Night.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/04-The Civil Wars _ I Had Me a Girl.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/05-The White Buffalo _ Oh Darlin' What Have I Done.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/06-America _ Man's Road.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/07-David Guetta feat. Sia _ She Wolf (Falling To Pieces).mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/08-Boston _ More Than a Feeling.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/09-Bob Dylan _ Like A Rolling Stone.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/10-Adele _ Set Fire To The Rain.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/11-Metallica _ Nothing Else Matters.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/12-Dolly Parton _ Jolene.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/13-Yes _ Owner Of A Lonely Heart.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/14-The White Stripes _ Seven Nation Army.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/15-Iggy Pop _ The Passenger.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/16-Ben Moon ft Veela _ Ben Moon ft Veela - Majesty.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Preloading song: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/17-Meat Loaf _ Life Is A Lemon And I Want My M.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F01-Murder%20By%20Death%20_%20Comin'%20Home.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/02-Andrea Bocelli _ Time To Say Goodbye (duet with.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F02-Andrea%20Bocelli%20_%20Time%20To%20Say%20Goodbye%20(duet%20with.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/02-Andrea Bocelli _ Time To Say Goodbye (duet with.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/03-Parov Stelar _ All Night.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F03-Parov%20Stelar%20_%20All%20Night.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/03-Parov Stelar _ All Night.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/04-The Civil Wars _ I Had Me a Girl.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F04-The%20Civil%20Wars%20_%20I%20Had%20Me%20a%20Girl.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/04-The Civil Wars _ I Had Me a Girl.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/05-The White Buffalo _ Oh Darlin' What Have I Done.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F05-The%20White%20Buffalo%20_%20Oh%20Darlin'%20What%20Have%20I%20Done.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/05-The White Buffalo _ Oh Darlin' What Have I Done.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/06-America _ Man's Road.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F06-America%20_%20Man's%20Road.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/06-America _ Man's Road.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/07-David Guetta feat. Sia _ She Wolf (Falling To Pieces).mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F07-David%20Guetta%20feat.%20Sia%20_%20She%20Wolf%20(Falling%20To%20Pieces).mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/07-David Guetta feat. Sia _ She Wolf (Falling To Pieces).mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/08-Boston _ More Than a Feeling.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F08-Boston%20_%20More%20Than%20a%20Feeling.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/08-Boston _ More Than a Feeling.mp3 Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/09-Bob Dylan _ Like A Rolling Stone.mp3 in service mpd Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F09-Bob%20Dylan%20_%20Like%20A%20Rolling%20Stone.mp3&metadata=false Feb 18 12:47:14 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/09-Bob Dylan _ Like A Rolling Stone.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/10-Adele _ Set Fire To The Rain.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F10-Adele%20_%20Set%20Fire%20To%20The%20Rain.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/10-Adele _ Set Fire To The Rain.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/11-Metallica _ Nothing Else Matters.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F11-Metallica%20_%20Nothing%20Else%20Matters.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/11-Metallica _ Nothing Else Matters.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/12-Dolly Parton _ Jolene.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F12-Dolly%20Parton%20_%20Jolene.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/12-Dolly Parton _ Jolene.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/13-Yes _ Owner Of A Lonely Heart.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F13-Yes%20_%20Owner%20Of%20A%20Lonely%20Heart.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/13-Yes _ Owner Of A Lonely Heart.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/14-The White Stripes _ Seven Nation Army.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F14-The%20White%20Stripes%20_%20Seven%20Nation%20Army.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/14-The White Stripes _ Seven Nation Army.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/15-Iggy Pop _ The Passenger.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F15-Iggy%20Pop%20_%20The%20Passenger.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/15-Iggy Pop _ The Passenger.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/16-Ben Moon ft Veela _ Ben Moon ft Veela - Majesty.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F16-Ben%20Moon%20ft%20Veela%20_%20Ben%20Moon%20ft%20Veela%20-%20Majesty.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/16-Ben Moon ft Veela _ Ben Moon ft Veela - Majesty.mp3 Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: Exploding uri music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/17-Meat Loaf _ Life Is A Lemon And I Want My M.mp3 in service mpd Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: ALBUMART /albumart?cacheid=354&web=Deda//extralarge&path=%2Fmnt%2FUSB%2FNew_Volume%2FAudiophile%20Collection%2FAudioPhile%205%2F17-Meat%20Loaf%20_%20Life%20Is%20A%20Lemon%20And%20I%20Want%20My%20M.mp3&metadata=false Feb 18 12:47:15 volumio-chen1 volumio[1101]: info: URI /mnt/USB/New_Volume/Audiophile Collection/AudioPhile 5/17-Meat Loaf _ Life Is A Lemon And I Want My M.mp3 Feb 18 12:47:17 volumio-chen1 thd[614]: {"time":1771393628665,"response":"next Success"}Executing trigger action: /usr/local/bin/volumio next Feb 18 12:47:17 volumio-chen1 thd[614]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 12:47:17 volumio-chen1 thd[614]: Dload Upload Total Spent Left Speed Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- Client requests Volumio next Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioNext Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::next Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 1 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:17 volumio-chen1 thd[614]: [158B blob data] Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 8 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 4 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 2 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 1 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 2 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav" Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 2","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/02 audio hi end test usher - Track 2.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:17 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 36ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 33ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 31ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 38ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav" took 37 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 10ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 8ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 25ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 22 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 21ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 20ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Feb 18 12:47:17 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 21 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 21 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 8 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 51ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 67ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 41 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":286,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:17 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 99ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 97ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: info: ------------------------------ 85ms Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:17 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:31 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:31+07:00" level=trace msg="sent dealer ping" Feb 18 12:47:31 volumio-chen1 go-librespot[1625]: time="2026-02-18T12:47:31+07:00" level=trace msg="received dealer pong" Feb 18 12:47:46 volumio-chen1 thd[614]: {"time":1771393637274,"response":"next Success"}Executing trigger action: /usr/local/bin/volumio next Feb 18 12:47:46 volumio-chen1 thd[614]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 12:47:46 volumio-chen1 thd[614]: Dload Upload Total Spent Left Speed Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- Client requests Volumio next Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioNext Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::next Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 2 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:46 volumio-chen1 thd[614]: [158B blob data] Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 22 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 10 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 8 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 4 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 3 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav" Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/03 audio hi end test usher - Track 3.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:46 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 42ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 30ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 28ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 27ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav" took 24 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 7ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 5ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 21ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 20 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 18ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 18ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 11 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 7 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Feb 18 12:47:46 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 6 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 2 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 2 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 43ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 48ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 46ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 4","artist":"audio hi end test usher","album":null,"uri":"USB/New_Volume/Audiophile Collection/audio hi end test usher/audio/04 audio hi end test usher - Track 4.wav","trackType":"wav"} Feb 18 12:47:46 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 78ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 72ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: info: ------------------------------ 72ms Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:46 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::ClearQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::stPlaybackTimer Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::serviceStop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::serviceStop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::clearPlayQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::addQueueItems Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::addQueueItems Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPlay Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::addQueueItems Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::addQueueItems Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Preload queue cleared Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/02-Andrea Bocelli _ Time To Say Goodbye (duet with.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/02-Andrea Bocelli _ Time To Say Goodbye (duet with.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/03-Parov Stelar _ All Night.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/03-Parov Stelar _ All Night.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/04-The Civil Wars _ I Had Me a Girl.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/04-The Civil Wars _ I Had Me a Girl.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/05-The White Buffalo _ Oh Darlin' What Have I Done.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/05-The White Buffalo _ Oh Darlin' What Have I Done.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/06-America _ Man's Road.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/06-America _ Man's Road.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/07-David Guetta feat. Sia _ She Wolf (Falling To Pieces).mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/07-David Guetta feat. Sia _ She Wolf (Falling To Pieces).mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/08-Boston _ More Than a Feeling.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/08-Boston _ More Than a Feeling.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/09-Bob Dylan _ Like A Rolling Stone.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/09-Bob Dylan _ Like A Rolling Stone.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/10-Adele _ Set Fire To The Rain.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/10-Adele _ Set Fire To The Rain.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/11-Metallica _ Nothing Else Matters.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/11-Metallica _ Nothing Else Matters.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/12-Dolly Parton _ Jolene.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/12-Dolly Parton _ Jolene.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/13-Yes _ Owner Of A Lonely Heart.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/13-Yes _ Owner Of A Lonely Heart.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/14-The White Stripes _ Seven Nation Army.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/14-The White Stripes _ Seven Nation Army.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/15-Iggy Pop _ The Passenger.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/15-Iggy Pop _ The Passenger.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/16-Ben Moon ft Veela _ Ben Moon ft Veela - Majesty.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/16-Ben Moon ft Veela _ Ben Moon ft Veela - Majesty.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Adding Item to queue: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/17-Meat Loaf _ Life Is A Lemon And I Want My M.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Using cached record of: music-library/USB/New_Volume/Audiophile Collection/AudioPhile 5/17-Meat Loaf _ Life Is A Lemon And I Want My M.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::saveQueue Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::play index undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::updateTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrackBlock Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::startPlaybackTimer Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::clearAddPlayTracks USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 43 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand stop took 8 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand clear Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 5 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand clear took 4 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 3 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 3 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand add "USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3" Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: No code Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 32ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 32ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: error: updateQueue error: null Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 38ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand add "USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3" took 38 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 8ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 6ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand play Feb 18 12:47:49 volumio-chen1 volumio[1101]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 46ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces system playlist update Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Ignoring MPD Status Update Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 40ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand play took 38 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 36ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 34ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 16 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 15 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 13 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Feb 18 12:47:49 volumio-chen1 volumio[1101]: ---------------------------- MPD announces state update: player Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::getState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 28 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 25 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand status took 14 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseState Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus stop Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 56ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 69ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 69ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: ControllerMpd::parseTrackInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ControllerMpd::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::servicePushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":216,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Murder By Death / Comin' Home","artist":"Deda","album":null,"uri":"USB/New_Volume/Audiophile Collection/AudioPhile 5/01-Murder By Death _ Comin' Home.mp3","trackType":"mp3"} Feb 18 12:47:49 volumio-chen1 volumio[1101]: verbose: CURRENT POSITION 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState stateService play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::syncState currentStatus play Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: Received an update from plugin. extracting info from payload Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreStateMachine::pushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CorePlayQueue::getTrack 0 Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: CoreCommandRouter::volumioPushState Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 112ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 109ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: info: ------------------------------ 101ms Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:49 volumio-chen1 volumio[1101]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 18 12:47:51 volumio-chen1 volumio[1101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 12:47:51 volumio-chen1 volumio[1101]: TypeError: Cannot read property 'slice' of null Feb 18 12:47:51 volumio-chen1 volumio[1101]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at Object.onceWrapper (events.js:520:26) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at ClientRequest.emit (events.js:400:28) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at TLSSocket.emit (events.js:400:28) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at addChunk (internal/streams/readable.js:293:12) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Feb 18 12:47:51 volumio-chen1 volumio[1101]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 18 12:47:51 volumio-chen1 volumio[1101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 12:47:52 volumio-chen1 sudo[9399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-18 12:46 Feb 18 12:47:52 volumio-chen1 sudo[9399]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"