-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2026-08-31 01:02:51 CST. -- Aug 31 01:01:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:08 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:08 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:08 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:18 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:18 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:28 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 01:01:28 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:28 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:28 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:28 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:28 volumio volumio[4811]: info: CoreCommandRouter::volumioClearQueue Aug 31 01:01:28 volumio volumio[4811]: info: CoreStateMachine::ClearQueue Aug 31 01:01:28 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:01:28 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:28 volumio volumio[4811]: info: CorePlayQueue::clearPlayQueue Aug 31 01:01:28 volumio volumio[4811]: info: CorePlayQueue::saveQueue Aug 31 01:01:28 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:28 volumio volumio[4811]: info: CoreCommandRouter::volumioPushQueue Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::ClearQueue Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::clearPlayQueue Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::saveQueue Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushQueue Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::addQueueItems Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::addQueueItems Aug 31 01:01:30 volumio volumio[4811]: info: Preload queue cleared Aug 31 01:01:30 volumio volumio[4811]: info: Adding Item to queue: volusonic/newest/7FlGvCKcyBMOhiNNw77R0h Aug 31 01:01:30 volumio volumio[4811]: info: Using cached record of: volusonic/newest/7FlGvCKcyBMOhiNNw77R0h Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushQueue Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::saveQueue Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::updateTrackBlock Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrackBlock Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPlay Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::play index 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::play index undefined Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::startPlaybackTimer Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290386] ControllerVolusonic::clearAddPlayTrack Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand stop took 8 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand clear took 2 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 8ms Aug 31 01:01:30 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 55ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 54ms Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand play Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 6ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand play took 6 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 5ms Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 5ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 2 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290480] ControllerVolusonic::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:30 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus stop Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290505] ControllerVolusonic: MPD player state update Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 9 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 8 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 6 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 14 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290535] ControllerVolusonic::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:30 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:01:30 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 52ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 50ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 48ms Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290561] ControllerVolusonic: MPD player state update Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 39 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 36 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 56ms Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:30 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:30 volumio volumio[4811]: info: Aug 31 01:01:30 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 20 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 22ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 19 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 17ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 15 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 15ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand status took 22 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 82ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 81ms Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:01:30 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: [1788109290611] ControllerVolusonic::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"545 Kbps","isStreaming":false,"title":"关不上的窗","artist":"周传雄","album":"恋人创世纪","uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:30 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:01:30 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:30 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:30 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:30 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 68ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 61ms Aug 31 01:01:30 volumio volumio[4811]: info: ------------------------------ 58ms Aug 31 01:01:35 volumio volumio[4811]: info: CoreCommandRouter::volumioGetQueue Aug 31 01:01:35 volumio volumio[4811]: info: CoreStateMachine::getQueue Aug 31 01:01:35 volumio volumio[4811]: info: CorePlayQueue::getQueue Aug 31 01:01:35 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:35 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:35 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:35 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:38 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:38 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:39 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:39 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:39 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:39 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:42 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:42 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:42 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:42 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:46 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:46 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:48 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:48 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:48 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:48 volumio volumio[4811]: info: Listing playlists Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioNext Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::next Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::stPlaybackTimer Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::updateTrackBlock Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrackBlock Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::serviceStop Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::serviceStop Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309364] ControllerVolusonic::stop Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::stop Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309397] ControllerVolusonic: MPD player state update Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand stop took 34 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 8 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 4 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309417] ControllerVolusonic::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: 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":"关不上的窗","artist":"周传雄","album":"恋人创世纪","uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:49 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: No code Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309439] ControllerVolusonic::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: 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":"关不上的窗","artist":"周传雄","album":"恋人创世纪","uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:49 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: No code Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 67ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 66ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 64ms Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::play index undefined Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::startPlaybackTimer Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309470] ControllerVolusonic::clearAddPlayTrack Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::updateTrackBlock Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrackBlock Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand stop took 13 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand clear took 27 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 13ms Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:01:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:01:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 97ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 96ms Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand play Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 3ms Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand play took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 5ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 5ms Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 3 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309628] ControllerVolusonic::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:49 volumio volumio[4811]: verbose: CURRENT POSITION 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus stop Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309657] ControllerVolusonic: MPD player state update Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 12 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 5 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309686] ControllerVolusonic::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:49 volumio volumio[4811]: verbose: CURRENT POSITION 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:01:49 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 53ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 51ms Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309714] ControllerVolusonic: MPD player state update Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 33 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 62ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 53ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 51ms Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:01:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioGetQueue Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::getQueue Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getQueue Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 25 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 28ms Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 23 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 16ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 14ms Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: Aug 31 01:01:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: [1788109309754] ControllerVolusonic::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":273,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"冬天的秘密","artist":"周传雄","album":"恋人创世纪","uri":"http://192.168.3.25:4533/rest/stream.view?id=x8xmm95nOYSro1rcXXgYnQ&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:01:49 volumio volumio[4811]: verbose: CURRENT POSITION 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:01:49 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 54ms Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 25 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand status took 23 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 01:01:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:01:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:01:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 47ms Aug 31 01:01:49 volumio volumio[4811]: info: ------------------------------ 45ms Aug 31 01:01:50 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 01:01:50 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 10 Aug 31 01:01:50 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 11 Aug 31 01:01:50 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 12 Aug 31 01:01:52 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:52 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:52 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:52 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:52 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:52 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:55 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:55 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:55 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:55 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:58 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:58 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:58 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:58 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:01:58 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:01:58 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:01 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:01 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:06 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:06 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetQueue Aug 31 01:02:08 volumio volumio[4811]: info: CoreStateMachine::getQueue Aug 31 01:02:08 volumio volumio[4811]: info: CorePlayQueue::getQueue Aug 31 01:02:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:08 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:08 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:08 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:08 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:08 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:08 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:08 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:10 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:10 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:11 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:11 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:13 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:13 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:15 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:15 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:17 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:17 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:18 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:18 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:19 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:19 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:21 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:21 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:23 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:23 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:25 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:25 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:27 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:27 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:28 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:28 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:28 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:28 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:29 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:29 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:31 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:31 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:33 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:33 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:35 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:35 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:37 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:37 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:38 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:38 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:39 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:39 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:41 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:41 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:43 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:43 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:45 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:45 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:47 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:47 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:48 volumio volumio[4811]: verbose: New Socket.io Connection to 192.168.3.40:3000 from 192.168.3.25 UA: Python/3.14 aiohttp/3.14.3 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 01:02:48 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:48 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:48 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:48 volumio volumio[4811]: info: Listing playlists Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::ClearQueue Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::stPlaybackTimer Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::updateTrackBlock Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrackBlock Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::serviceStop Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 1 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::serviceStop Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369145] ControllerVolusonic::stop Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::stop Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::clearPlayQueue Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::saveQueue Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushQueue Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::addQueueItems Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::addQueueItems Aug 31 01:02:49 volumio volumio[4811]: info: Preload queue cleared Aug 31 01:02:49 volumio volumio[4811]: info: Adding Item to queue: volusonic/newest/7FlGvCKcyBMOhiNNw77R0h Aug 31 01:02:49 volumio volumio[4811]: info: Using cached record of: volusonic/newest/7FlGvCKcyBMOhiNNw77R0h Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushQueue Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::saveQueue Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::updateTrackBlock Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrackBlock Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPlay Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::play index 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::stop Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::play index undefined Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::startPlaybackTimer Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369173] ControllerVolusonic::clearAddPlayTrack Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369180] ControllerVolusonic: MPD player state update Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand stop took 37 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand stop took 9 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 18 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 16 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 16 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand clear took 16 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 6 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 5 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 24ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 22ms Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 27ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 48ms Aug 31 01:02:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:02:49 volumio volumio[4811]: error: updateQueue error: null Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 47ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 45ms Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand add "http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic" took 2 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand play Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 9ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand play took 8 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 5ms Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 8ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 5 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369288] ControllerVolusonic::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:02:49 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus stop Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369315] ControllerVolusonic: MPD player state update Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 9 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 9 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 7 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 16 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 14 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 9 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369348] ControllerVolusonic::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:02:49 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:02:49 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 55ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 53ms Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369389] ControllerVolusonic: MPD player state update Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 55 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 49 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 48 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 12ms Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 82ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 79ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 71ms Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces system playlist update Aug 31 01:02:49 volumio volumio[4811]: info: Ignoring MPD Status Update Aug 31 01:02:49 volumio volumio[4811]: info: Aug 31 01:02:49 volumio volumio[4811]: ---------------------------- MPD announces state update: player Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::getState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand status Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioGetQueue Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::getQueue Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getQueue Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 38 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 34 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 37ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 30 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 22ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand status took 21 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseState Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 99ms Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 01:02:49 volumio volumio[4811]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: verbose: ControllerMpd::parseTrackInfo Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: [1788109369445] ControllerVolusonic::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"545 Kbps","isStreaming":false,"title":"关不上的窗","artist":"周传雄","album":"恋人创世纪","uri":"http://192.168.3.25:4533/rest/stream.view?id=YoRT0nW35iStdHheULduXg&format=raw&u=lixiaofeng&t=1ca78d0b0b526520e157e42f98602c90&s=3lGt5&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 31 01:02:49 volumio volumio[4811]: verbose: CURRENT POSITION 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState stateService play Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::syncState currentStatus play Aug 31 01:02:49 volumio volumio[4811]: info: Received an update from plugin. extracting info from payload Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreStateMachine::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::volumioPushState Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ControllerMpd::pushState Aug 31 01:02:49 volumio volumio[4811]: info: CoreCommandRouter::servicePushState Aug 31 01:02:49 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:49 volumio volumio[4811]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 73ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 82ms Aug 31 01:02:49 volumio volumio[4811]: info: ------------------------------ 68ms Aug 31 01:02:50 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:50 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:50 volumio volumio[4811]: info: CoreCommandRouter::volumioGetState Aug 31 01:02:50 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:50 volumio volumio[4811]: info: CorePlayQueue::getTrack 0 Aug 31 01:02:50 volumio volumio[4811]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 01:02:50 volumio volumio[4811]: TypeError: Cannot create property 'uri' on number '1' Aug 31 01:02:50 volumio volumio[4811]: at CoreMusicLibrary.goto (/volumio/app/musiclibrary.js:573:14) Aug 31 01:02:50 volumio volumio[4811]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:507:44) Aug 31 01:02:50 volumio volumio[4811]: at Socket.emit (events.js:315:20) Aug 31 01:02:50 volumio volumio[4811]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 31 01:02:50 volumio volumio[4811]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 31 01:02:50 volumio volumio[4811]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 01:02:51 volumio sudo[26855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 01:01 Aug 31 01:02:51 volumio sudo[26855]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"