-- Logs begin at Thu 2025-07-10 20:59:57 CST, end at Thu 2025-07-10 21:23:22 CST. -- Jul 10 21:22:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 21:22:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 21:22:11 v3tb16pii volumio[1265]: info: Discovery: Getting this device information Jul 10 21:22:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:11 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 4 Jul 10 21:22:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 21:22:12 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:12 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 4 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioNext Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::next Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 4 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::serviceStop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 4 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::serviceStop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::stop Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Jul 10 21:22:17 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 30 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::play index undefined Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand status took 4 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 2 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Jul 10 21:22:17 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand clear took 2 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape" Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: 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":"05 - My Love.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/05 - My Love.ape","trackType":"ape"} Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: No code Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ------------------------------ 66ms Jul 10 21:22:17 v3tb16pii volumio[1265]: error: updateQueue error: null Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Jul 10 21:22:17 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ------------------------------ 60ms Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape" took 58 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ------------------------------ 9ms Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand play took 9 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Jul 10 21:22:17 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Jul 10 21:22:17 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand status took 3 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand status took 1 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06 - The Cup Of Life.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape","trackType":"ape"} Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ------------------------------ 9ms Jul 10 21:22:17 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06 - The Cup Of Life.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape","trackType":"ape"} Jul 10 21:22:17 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:22:17 v3tb16pii volumio[1265]: info: Received an update from plugin. extracting info from payload Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:17 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:17 v3tb16pii volumio[1265]: info: ------------------------------ 48ms Jul 10 21:22:20 v3tb16pii volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 21:22:20 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:20 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioNext Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::next Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::serviceStop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 5 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::serviceStop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::stop Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Jul 10 21:22:24 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 21 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::play index undefined Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand status took 7 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 4 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Jul 10 21:22:24 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand clear took 2 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape" Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: 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":"06 - The Cup Of Life.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/06 - The Cup Of Life.ape","trackType":"ape"} Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: No code Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ------------------------------ 73ms Jul 10 21:22:24 v3tb16pii volumio[1265]: error: updateQueue error: null Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Jul 10 21:22:24 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ------------------------------ 65ms Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape" took 64 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ------------------------------ 9ms Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand play took 8 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Jul 10 21:22:24 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Jul 10 21:22:24 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand status took 5 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand status took 1 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 - Better Man.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape","trackType":"ape"} Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ------------------------------ 12ms Jul 10 21:22:24 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 - Better Man.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape","trackType":"ape"} Jul 10 21:22:24 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:22:24 v3tb16pii volumio[1265]: info: Received an update from plugin. extracting info from payload Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:24 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:24 v3tb16pii volumio[1265]: info: ------------------------------ 51ms Jul 10 21:22:27 v3tb16pii volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 21:22:27 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:27 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:27 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:27 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:28 v3tb16pii volumio[1265]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 10 21:22:28 v3tb16pii volumio[1265]: info: Retrying Login Due to Network Error Jul 10 21:22:38 v3tb16pii volumio[1265]: info: MyVolumio login type: Token Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioNext Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::next Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::serviceStop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 6 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::serviceStop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ControllerMpd::stop Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: Jul 10 21:22:38 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 40 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::play index undefined Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand status took 5 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 2 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:22:38 v3tb16pii volumio[1265]: info: Jul 10 21:22:38 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:38 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand clear took 3 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape" Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: 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":"07 - Better Man.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/07 - Better Man.ape","trackType":"ape"} Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService stop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: No code Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:38 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ------------------------------ 57ms Jul 10 21:22:38 v3tb16pii volumio[1265]: error: updateQueue error: null Jul 10 21:22:38 v3tb16pii volumio[1265]: info: Jul 10 21:22:38 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:38 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ------------------------------ 51ms Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand add "NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape" took 50 milliseconds Jul 10 21:22:38 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:22:38 v3tb16pii volumio[1265]: info: ------------------------------ 14ms Jul 10 21:22:38 v3tb16pii volumio[1265]: info: sendMpdCommand play took 12 milliseconds Jul 10 21:22:39 v3tb16pii volumio[1265]: info: Jul 10 21:22:39 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:39 v3tb16pii volumio[1265]: info: Jul 10 21:22:39 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:39 v3tb16pii volumio[1265]: info: sendMpdCommand status took 6 milliseconds Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:39 v3tb16pii volumio[1265]: info: sendMpdCommand status took 2 milliseconds Jul 10 21:22:39 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - I Swear.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape","trackType":"ape"} Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ------------------------------ 17ms Jul 10 21:22:39 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 - I Swear.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape","trackType":"ape"} Jul 10 21:22:39 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:22:39 v3tb16pii volumio[1265]: info: Received an update from plugin. extracting info from payload Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:39 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:39 v3tb16pii volumio[1265]: info: ------------------------------ 61ms Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 21:22:41 v3tb16pii volumio[1265]: info: Discovery: Getting this device information Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 21:22:41 v3tb16pii volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:41 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:42 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:42 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Preload queue cleared Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::ClearQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::serviceStop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 7 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::serviceStop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ControllerMpd::stop Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::clearPlayQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::saveQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::addQueueItems Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::addQueueItems Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Preload queue cleared Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Adding Item to queue: music-library/NAS/Music_Box/00CD/费翔《百老汇精选》 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Exploding uri music-library/NAS/Music_Box/00CD/费翔《百老汇精选》 in service mpd Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Jul 10 21:22:50 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F01.%20%E5%83%8F%E6%B2%A1%E8%AF%B4%E8%BF%87%E5%86%8D%E8%A7%81%E3%80%8A%E6%97%A5%E8%90%BD%E5%A4%A7%E9%81%93%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F02.%20%E5%9B%9E%E5%BF%86%E3%80%8A%E7%8C%AB%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F03.%20%E6%AD%8C%E5%89%A7%E9%AD%85%E5%BD%B1%E3%80%8A%E5%89%A7%E9%99%A2%E5%B9%BD%E7%81%B5%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/03. 歌剧魅影《剧院幽灵》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F04.%20MUSIC%20OF%20THE%20NIGHT%E3%80%8A%E5%89%A7%E9%99%A2%E5%B9%BD%E7%81%B5%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/04. MUSIC OF THE NIGHT《剧院幽灵》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F05.%20%E7%8E%9B%E5%88%A9%E4%BA%9A%E3%80%8A%E8%A5%BF%E5%9F%8E%E6%95%85%E4%BA%8B%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/05. 玛利亚《西城故事》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F06.%20%E6%B0%B8%E6%81%92%E7%9A%84%E5%85%89%E8%8A%92%E3%80%8A%E5%8C%96%E8%BA%AB%E5%8D%9A%E5%A3%AB%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/06. 永恒的光芒《化身博士》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F07.%20%E4%BA%BA%E5%8E%BB%E6%A5%BC%E7%A9%BA%E3%80%8A%E6%82%B2%E6%83%A8%E4%B8%96%E7%95%8C%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/07. 人去楼空《悲惨世界》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F08.%20%E5%8F%AA%E6%9C%89%E4%BD%A0%E3%80%8A%E6%98%9F%E5%85%89%E5%88%97%E8%BD%A6%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/08. 只有你《星光列车》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F09.%20%E5%88%86%E6%89%8B%E7%9A%84%E6%97%B6%E5%80%99%E3%80%8A%E5%87%A4%E5%AE%AB%E5%8A%AB%E7%BE%8E%E5%BD%95%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/09. 分手的时候《凤宫劫美录》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F10.%20%E6%97%A5%E5%87%BA%E6%97%A5%E8%90%BD%E3%80%8A%E5%B1%8B%E9%A1%B6%E4%B8%8A%E7%9A%84%E6%8F%90%E7%90%B4%E6%89%8B%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/10. 日出日落《屋顶上的提琴手》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F11.%20%E6%88%91%E4%B8%8D%E8%83%BD%E6%B2%A1%E6%9C%89%E4%BD%A0%E3%80%8A%E5%89%A7%E9%99%A2%E5%B9%BD%E7%81%B5%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/11. 我不能没有你《剧院幽灵》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F12.%20UNEXPECTED%20SONG%E3%80%8A%E8%BD%BB%E6%AD%8C%E5%A6%99%E8%88%9E%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/12. UNEXPECTED SONG《轻歌妙舞》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F13.%20%E4%B8%80%E5%A4%9C%E6%83%85%E3%80%8A%E6%A2%A6%E5%B9%BB%E5%A5%B3%E9%83%8E%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/13. 一夜情《梦幻女郎》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F14.%20%E8%89%AF%E5%AE%B5%E8%8A%B1%E6%9C%88%E5%A4%9C%E3%80%8A%E5%8D%97%E5%A4%AA%E5%B9%B3%E6%B4%8B%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/14. 良宵花月夜《南太平洋》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ALBUMART /albumart?cacheid=12&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E8%B4%B9%E7%BF%94%E3%80%8A%E7%99%BE%E8%80%81%E6%B1%87%E7%B2%BE%E9%80%89%E3%80%8B%2F15.%20%E7%88%B1%E8%BF%87%E6%97%A0%E6%80%A8%E3%80%8A%E6%AD%8C%E8%88%9E%E7%BA%BF%E4%B8%8A%E3%80%8B.ape&metadata=false Jul 10 21:22:50 v3tb16pii volumio[1265]: info: URI /mnt/NAS/Music_Box/00CD/费翔《百老汇精选》/15. 爱过无怨《歌舞线上》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 143 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::saveQueue Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPlay Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::play index 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::play index undefined Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand status took 33 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 14 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Jul 10 21:22:50 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand clear took 8 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape" Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: 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":"08 - I Swear.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/爱浪.星际唱游CD2/08 - I Swear.ape","trackType":"ape"} Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: No code Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:50 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ------------------------------ 127ms Jul 10 21:22:50 v3tb16pii volumio[1265]: error: updateQueue error: null Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Jul 10 21:22:50 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:50 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ------------------------------ 78ms Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand add "NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape" took 77 milliseconds Jul 10 21:22:50 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:22:50 v3tb16pii volumio[1265]: info: ------------------------------ 20ms Jul 10 21:22:50 v3tb16pii volumio[1265]: info: sendMpdCommand play took 17 milliseconds Jul 10 21:22:51 v3tb16pii volumio[1265]: info: Jul 10 21:22:51 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:51 v3tb16pii volumio[1265]: info: Jul 10 21:22:51 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:51 v3tb16pii volumio[1265]: info: sendMpdCommand status took 2 milliseconds Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:51 v3tb16pii volumio[1265]: info: sendMpdCommand status took 2 milliseconds Jul 10 21:22:51 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1070,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. 像没说过再见《日落大道》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape","trackType":"ape"} Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ------------------------------ 6ms Jul 10 21:22:51 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1070,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. 像没说过再见《日落大道》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape","trackType":"ape"} Jul 10 21:22:51 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:22:51 v3tb16pii volumio[1265]: info: Received an update from plugin. extracting info from payload Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:51 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:51 v3tb16pii volumio[1265]: info: ------------------------------ 49ms Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioNext Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::next Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::stop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::serviceStop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 0 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::serviceStop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ControllerMpd::stop Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: Jul 10 21:22:53 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 32 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::play index undefined Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::startPlaybackTimer Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::updateTrackBlock Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrackBlock Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand status took 4 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand stop took 5 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: 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":"01. 像没说过再见《日落大道》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/01. 像没说过再见《日落大道》.ape","trackType":"ape"} Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService stop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: No code Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ------------------------------ 66ms Jul 10 21:22:53 v3tb16pii volumio[1265]: info: Jul 10 21:22:53 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:53 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand clear took 58 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape" Jul 10 21:22:53 v3tb16pii volumio[1265]: error: updateQueue error: null Jul 10 21:22:53 v3tb16pii volumio[1265]: info: Jul 10 21:22:53 v3tb16pii volumio[1265]: ---------------------------- MPD announces system playlist update Jul 10 21:22:53 v3tb16pii volumio[1265]: info: Ignoring MPD Status Update Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ------------------------------ 13ms Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand add "NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape" took 11 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Jul 10 21:22:53 v3tb16pii volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 21:22:53 v3tb16pii volumio[1265]: info: ------------------------------ 8ms Jul 10 21:22:53 v3tb16pii volumio[1265]: info: sendMpdCommand play took 8 milliseconds Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:53 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: info: Jul 10 21:22:54 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:54 v3tb16pii volumio[1265]: info: Jul 10 21:22:54 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:22:54 v3tb16pii volumio[1265]: info: sendMpdCommand status took 7 milliseconds Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:54 v3tb16pii volumio[1265]: info: sendMpdCommand status took 2 milliseconds Jul 10 21:22:54 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02. 回忆《猫》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape","trackType":"ape"} Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus stop Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ------------------------------ 14ms Jul 10 21:22:54 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02. 回忆《猫》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape","trackType":"ape"} Jul 10 21:22:54 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService play Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus play Jul 10 21:22:54 v3tb16pii volumio[1265]: info: Received an update from plugin. extracting info from payload Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:54 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:22:54 v3tb16pii volumio[1265]: info: ------------------------------ 48ms Jul 10 21:22:56 v3tb16pii volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 10 21:22:56 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:56 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:22:57 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:22:57 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:08 v3tb16pii volumio[1265]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 10 21:23:08 v3tb16pii volumio[1265]: info: Retrying Login Due to Network Error Jul 10 21:23:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 21:23:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 21:23:11 v3tb16pii volumio[1265]: info: Discovery: Getting this device information Jul 10 21:23:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:23:11 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:11 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 21:23:12 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioGetState Jul 10 21:23:12 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:18 v3tb16pii volumio[1265]: info: MyVolumio login type: Token Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::pause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::servicePause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: ControllerMpd::pause Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: Jul 10 21:23:22 v3tb16pii volumio[1265]: ---------------------------- MPD announces state update: player Jul 10 21:23:22 v3tb16pii volumio[1265]: info: sendMpdCommand pause took 4 milliseconds Jul 10 21:23:22 v3tb16pii volumio[1265]: info: ControllerMpd::getState Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Jul 10 21:23:22 v3tb16pii volumio[1265]: info: sendMpdCommand status took 2 milliseconds Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseState Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 21:23:22 v3tb16pii volumio[1265]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: ControllerMpd::parseTrackInfo Jul 10 21:23:22 v3tb16pii volumio[1265]: info: ControllerMpd::pushState Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreCommandRouter::servicePushState Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":28935,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02. 回忆《猫》.ape","artist":null,"album":null,"uri":"NAS/Music_Box/00CD/费翔《百老汇精选》/02. 回忆《猫》.ape","trackType":"ape"} Jul 10 21:23:22 v3tb16pii volumio[1265]: verbose: CURRENT POSITION 1 Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState stateService pause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::syncState currentStatus pause Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::pushState Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CorePlayQueue::getTrack 1 Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreCommandRouter::volumioPushState Jul 10 21:23:22 v3tb16pii volumio[1265]: info: CoreStateMachine::stPlaybackTimer Jul 10 21:23:22 v3tb16pii volumio[1265]: info: ------------------------------ 42ms Jul 10 21:23:22 v3tb16pii volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 21:23:22 v3tb16pii volumio[1265]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 10 21:23:22 v3tb16pii volumio[1265]: at Object.readdirSync (fs.js:1048:3) Jul 10 21:23:22 v3tb16pii volumio[1265]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jul 10 21:23:22 v3tb16pii volumio[1265]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jul 10 21:23:22 v3tb16pii volumio[1265]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jul 10 21:23:22 v3tb16pii volumio[1265]: errno: -20, Jul 10 21:23:22 v3tb16pii volumio[1265]: syscall: 'scandir', Jul 10 21:23:22 v3tb16pii volumio[1265]: code: 'ENOTDIR', Jul 10 21:23:22 v3tb16pii volumio[1265]: path: '/tmp/peppyrunning' Jul 10 21:23:22 v3tb16pii volumio[1265]: } Jul 10 21:23:22 v3tb16pii volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 21:23:22 v3tb16pii sudo[6398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 21:22 Jul 10 21:23:22 v3tb16pii sudo[6398]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"