-- Logs begin at Thu 2025-06-26 06:50:57 UTC, end at Thu 2025-07-17 10:30:09 UTC. -- Jul 17 10:29:02 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:12 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:18 idsd-phantom volumiologrotate[390]: ls: cannot access '/var/log/samba/log.wb-IDSD': No such file or directory Jul 17 10:29:18 idsd-phantom volumiologrotate[390]: ls: cannot access 'PHANTOM': No such file or directory Jul 17 10:29:18 idsd-phantom nmbd[663]: [2025/07/17 10:29:18.931283, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 17 10:29:18 idsd-phantom nmbd[663]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.90 for name WORKGROUP<1d>. Jul 17 10:29:18 idsd-phantom nmbd[663]: This response was from IP 192.168.50.53, reporting an IP address of 192.168.50.53. Jul 17 10:29:18 idsd-phantom nmbd[663]: [2025/07/17 10:29:18.931906, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 17 10:29:18 idsd-phantom nmbd[663]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.46 for name WORKGROUP<1d>. Jul 17 10:29:18 idsd-phantom nmbd[663]: This response was from IP 192.168.50.53, reporting an IP address of 192.168.50.53. Jul 17 10:29:22 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:32 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:39 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:29:39 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 17ms Jul 17 10:29:39 idsd-phantom volumio[1119]: info: sendMpdCommand status took 17 milliseconds Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 16ms Jul 17 10:29:39 idsd-phantom volumio[1119]: info: sendMpdCommand status took 15 milliseconds Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 32 Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 32 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 32 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 32 Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 32 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService stop Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::play index undefined Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService stop Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: No code Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::clearAddPlayTracks NAS/192.168.1.220/k2_test/pink_192_16/Pink_FS192_16.wav Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 129ms Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 128ms Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:39 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:39 idsd-phantom volumio[1119]: info: sendMpdCommand stop took 26 milliseconds Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand clear Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: sendMpdCommand clear took 12 milliseconds Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_192_16/Pink_FS192_16.wav" Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:39 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 14ms Jul 17 10:29:39 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_192_16/Pink_FS192_16.wav" took 11 milliseconds Jul 17 10:29:39 idsd-phantom volumio[1119]: info: ------------------------------ 9ms Jul 17 10:29:39 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand play Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Jul 17 10:29:39 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:29:39 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:29:42 idsd-phantom demo[720]: *************************************************** Jul 17 10:29:42 idsd-phantom demo[720]: format: DSD Jul 17 10:29:42 idsd-phantom demo[720]: base: 0 times: 2 Jul 17 10:29:42 idsd-phantom demo[720]: in rate: 88200 Jul 17 10:29:42 idsd-phantom demo[720]: base: 0 times: 12 Jul 17 10:29:42 idsd-phantom demo[720]: out rate: 90316800 Jul 17 10:29:42 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:29:42 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:29:42 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:29:42 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:29:42 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:29:42 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:29:42 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:29:42 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:29:42 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:29:42 idsd-phantom demo[720]: *************************************************** Jul 17 10:29:42 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 31 01 fa 45 Jul 17 10:29:42 idsd-phantom demo[720]: CMD_XMOS_STREAM_IDLE_STATE Jul 17 10:29:42 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:52 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:29:56 idsd-phantom volumio-remote-updater[396]: [2025-07-17 10:29:56] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jul 17 10:29:59 idsd-phantom demo[720]: alsa no stream Jul 17 10:29:59 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 2d 02 8c 38 Jul 17 10:29:59 idsd-phantom demo[720]: SET_FILTER_IO_STATE Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Jul 17 10:29:59 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ------------------------------ 20136ms Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand play took 20132 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ------------------------------ 20131ms Jul 17 10:29:59 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 31 00 ea 64 Jul 17 10:29:59 idsd-phantom demo[720]: CMD_XMOS_STREAM_IDLE_STATE Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Jul 17 10:29:59 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Jul 17 10:29:59 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand status took 52 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand status took 48 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Jul 17 10:29:59 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: New Socket.io Connection to 192.168.50.46:3000 from 192.168.50.93 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Discovery: Disconnected from remote: 192.168.50.93 Jul 17 10:29:59 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Discovery: Disconnected from remote: 192.168.50.93 Jul 17 10:29:59 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand status took 66 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 58 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: info: sendMpdCommand status took 54 milliseconds Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":1,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS192_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_192_16/Pink_FS192_16.wav","trackType":"wav"} Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":993,"duration":1,"samplerate":"192 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS192_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_192_16/Pink_FS192_16.wav","trackType":"wav"} Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:29:59 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:29:59 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ------------------------------ 168ms Jul 17 10:29:59 idsd-phantom volumio[1119]: info: ------------------------------ 170ms Jul 17 10:29:59 idsd-phantom demo[720]: alsa has stream Jul 17 10:29:59 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 83 02 8c 04 01 08 1d 03 0f a2 bb Jul 17 10:29:59 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:29:59 idsd-phantom volumio[1119]: verbose: New Socket.io Connection to 192.168.50.46:3000 from 192.168.50.93 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ++++--++++ initSocket: {"id":"b8ed04b3-524a-4eda-9e6a-0b84a459aff5"} Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ++++--++++ getState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Discovery: Connected to remote: 192.168.50.93 Jul 17 10:30:00 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:30:00 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:30:00 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:30:00 idsd-phantom volumio[1119]: error: Could not retrieve plugin audio_interface multiroom Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:30:00 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 881 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 876 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 13ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 11 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 9ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 7 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 17 10:30:00 idsd-phantom volumio[1119]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 33 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService stop Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::play index undefined Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService stop Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: No code Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 1008ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 993ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::clearAddPlayTracks NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand stop Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 101ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 99ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand stop took 23 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand clear Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand clear took 6 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav" Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: error: updateQueue error: null Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 15ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav" took 11 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 10ms Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 82ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand play took 79 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 77ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:00 idsd-phantom demo[720]: format: DSD Jul 17 10:30:00 idsd-phantom demo[720]: base: 1 times: 3 Jul 17 10:30:00 idsd-phantom demo[720]: in rate: 192000 Jul 17 10:30:00 idsd-phantom demo[720]: base: 1 times: 12 Jul 17 10:30:00 idsd-phantom demo[720]: out rate: 98304000 Jul 17 10:30:00 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:00 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:00 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:00 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:00 idsd-phantom demo[720]: available filter bit mask: 0x1d Jul 17 10:30:00 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:00 idsd-phantom demo[720]: available k2 bit mask: 0x3 Jul 17 10:30:00 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:00 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:00 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:00 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 2d 02 8c 38 Jul 17 10:30:00 idsd-phantom demo[720]: SET_FILTER_IO_STATE Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 33 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 35 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Prefetching next song Jul 17 10:30:00 idsd-phantom volumio[1119]: info: DOING PREFETCH IN MPD Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_44_24/Pink_FS441_24.wav" Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 41 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav","trackType":"wav"} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus stop Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 57ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_44_24/Pink_FS441_24.wav" took 31 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 27 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand status took 16 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":1,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav","trackType":"wav"} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 114ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 64ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand consume 1 took 48 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 8ms Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":1,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav","trackType":"wav"} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":1,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_16/Pink_FS441_16.wav","trackType":"wav"} Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 34 Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:00 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 140ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 128ms Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:00 idsd-phantom volumio[1119]: info: Jul 17 10:30:00 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:00 idsd-phantom volumio[1119]: info: ------------------------------ 31ms Jul 17 10:30:00 idsd-phantom volumio[1119]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 17 10:30:01 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 01 02 0c 04 01 08 1f 03 0f 1e f7 Jul 17 10:30:01 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 35 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 35 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Prefetching next song Jul 17 10:30:01 idsd-phantom volumio[1119]: info: DOING PREFETCH IN MPD Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_96_16/Pink_FS96_16.wav" Jul 17 10:30:01 idsd-phantom volumio-remote-updater[396]: [2025-07-17 10:30:01] [connect] Successful connection Jul 17 10:30:01 idsd-phantom volumio-remote-updater[396]: [2025-07-17 10:30:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752748201 101 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Prefetching next song Jul 17 10:30:01 idsd-phantom volumio[1119]: info: DOING PREFETCH IN MPD Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/sin_44_16/Sin_FS441_16.wav" Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand status took 900 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Jul 17 10:30:01 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Jul 17 10:30:01 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ------------------------------ 924ms Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand status took 922 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/pink_96_16/Pink_FS96_16.wav" took 591 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/sin_44_16/Sin_FS441_16.wav" took 88 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_24.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_24/Pink_FS441_24.wav","trackType":"wav"} Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ------------------------------ 999ms Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Jul 17 10:30:01 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand status took 61 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ------------------------------ 64ms Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand consume 1 took 56 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand consume 1 took 56 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand status took 13 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_24.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_24/Pink_FS441_24.wav","trackType":"wav"} Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ------------------------------ 1038ms Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Jul 17 10:30:01 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:01 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS441_24.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_44_24/Pink_FS441_24.wav","trackType":"wav"} Jul 17 10:30:01 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:01 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 36 Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:01 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:01 idsd-phantom volumio[1119]: info: ------------------------------ 185ms Jul 17 10:30:02 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:02 idsd-phantom demo[720]: format: DSD Jul 17 10:30:02 idsd-phantom demo[720]: base: 0 times: 1 Jul 17 10:30:02 idsd-phantom demo[720]: in rate: 44100 Jul 17 10:30:02 idsd-phantom demo[720]: base: 0 times: 12 Jul 17 10:30:02 idsd-phantom demo[720]: out rate: 90316800 Jul 17 10:30:02 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:02 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:02 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:02 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:02 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:30:02 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:02 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:30:02 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:02 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:02 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:02 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 01 02 0c 04 01 08 1f 03 0f 1e f7 Jul 17 10:30:02 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Prefetching next song Jul 17 10:30:02 idsd-phantom volumio[1119]: info: DOING PREFETCH IN MPD Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/k2_test/sin_44_24/Sin_FS441_24.wav" Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:02 idsd-phantom demo[720]: format: DSD Jul 17 10:30:02 idsd-phantom demo[720]: base: 0 times: 1 Jul 17 10:30:02 idsd-phantom demo[720]: in rate: 44100 Jul 17 10:30:02 idsd-phantom demo[720]: base: 0 times: 12 Jul 17 10:30:02 idsd-phantom demo[720]: out rate: 90316800 Jul 17 10:30:02 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:02 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:02 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:02 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:02 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:30:02 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:02 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:30:02 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:02 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:02 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:02 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 2d 02 8c 38 Jul 17 10:30:02 idsd-phantom demo[720]: SET_FILTER_IO_STATE Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 1012 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 970ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/k2_test/sin_44_24/Sin_FS441_24.wav" took 643 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 12ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand status took 9 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS96_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_96_16/Pink_FS96_16.wav","trackType":"wav"} Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 1110ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 92ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand consume 1 took 85 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 81 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 12ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: sendMpdCommand status took 8 milliseconds Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":1,"samplerate":"96 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pink_FS96_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/pink_96_16/Pink_FS96_16.wav","trackType":"wav"} Jul 17 10:30:02 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 37 Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:02 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:02 idsd-phantom volumio[1119]: info: ------------------------------ 162ms Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Jul 17 10:30:02 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:02 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:03 idsd-phantom ethernet-detect[444]: No change in avahi-daemon interfaces. Skipping restart. Jul 17 10:30:03 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 82 02 8c 04 01 08 1f 03 0f 27 f8 Jul 17 10:30:03 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Prefetching next song Jul 17 10:30:03 idsd-phantom volumio[1119]: info: DOING PREFETCH IN MPD Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand add "NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3" Jul 17 10:30:03 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:03 idsd-phantom demo[720]: format: DSD Jul 17 10:30:03 idsd-phantom demo[720]: base: 1 times: 2 Jul 17 10:30:03 idsd-phantom demo[720]: in rate: 96000 Jul 17 10:30:03 idsd-phantom demo[720]: base: 1 times: 12 Jul 17 10:30:03 idsd-phantom demo[720]: out rate: 98304000 Jul 17 10:30:03 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:03 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:03 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:03 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:03 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:30:03 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:03 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:30:03 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:03 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:03 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:03 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 04 2d 02 8c 38 Jul 17 10:30:03 idsd-phantom demo[720]: SET_FILTER_IO_STATE Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Jul 17 10:30:03 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Jul 17 10:30:03 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:03 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Jul 17 10:30:03 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:03 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 1021 milliseconds Jul 17 10:30:03 idsd-phantom volumio[1119]: info: ------------------------------ 963ms Jul 17 10:30:03 idsd-phantom volumio[1119]: info: sendMpdCommand add "NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3" took 185 milliseconds Jul 17 10:30:03 idsd-phantom volumio[1119]: info: ------------------------------ 12ms Jul 17 10:30:03 idsd-phantom volumio[1119]: info: sendMpdCommand status took 9 milliseconds Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:03 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":1,"samplerate":"96 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sin_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/sin_44_16/Sin_FS441_16.wav","trackType":"wav"} Jul 17 10:30:03 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:03 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:03 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 1094ms Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Jul 17 10:30:04 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Jul 17 10:30:04 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 67ms Jul 17 10:30:04 idsd-phantom volumio[1119]: info: sendMpdCommand consume 1 took 60 milliseconds Jul 17 10:30:04 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 7ms Jul 17 10:30:04 idsd-phantom volumio[1119]: info: sendMpdCommand status took 6 milliseconds Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":2,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sin_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/sin_44_16/Sin_FS441_16.wav","trackType":"wav"} Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 121ms Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Jul 17 10:30:04 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:04 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 12ms Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":2,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sin_FS441_16.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/sin_44_16/Sin_FS441_16.wav","trackType":"wav"} Jul 17 10:30:04 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:04 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: ------------------------------ 109ms Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 38 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::startPlaybackTimer Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:04 idsd-phantom volumio[1119]: error: Could not retrieve plugin miscellanea metavolumio Jul 17 10:30:04 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 01 02 0c 04 01 08 1f 03 0f 1e f7 Jul 17 10:30:04 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:04 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:05 idsd-phantom volumio[1119]: info: MetaClient: Sent Meta Jul 17 10:30:05 idsd-phantom volumio[1119]: info: MetaClient: Get message: success Jul 17 10:30:05 idsd-phantom node[443]: metadata.title changed Jul 17 10:30:05 idsd-phantom node[443]: metadata.album: http://127.0.0.1:3000/albumart?cacheid=589&web=%E6%9D%A8%E4%B9%8B%E6%AD%A3%E8%8D%A3%E8%AA%89%E5%87%BA%E5%93%81//extralarge&path=%2FNAS%2F192.168.1.220%2FRoon%20Imports%2FKAYH%2Fimported%20at%202022-07-09T09-25%20UTC_08_00&metadata=false Jul 17 10:30:05 idsd-phantom volumio[1119]: info: Jul 17 10:30:05 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:05 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:05 idsd-phantom volumio[1119]: info: Jul 17 10:30:05 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:05 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:05 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ------------------------------ 16ms Jul 17 10:30:06 idsd-phantom volumio[1119]: info: sendMpdCommand status took 12 milliseconds Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ------------------------------ 10ms Jul 17 10:30:06 idsd-phantom volumio[1119]: info: sendMpdCommand status took 8 milliseconds Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 17 10:30:06 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sin_FS441_24.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/sin_44_24/Sin_FS441_24.wav","trackType":"wav"} Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":1,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sin_FS441_24.wav","artist":null,"album":null,"uri":"NAS/192.168.1.220/k2_test/sin_44_24/Sin_FS441_24.wav","trackType":"wav"} Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:06 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ------------------------------ 129ms Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ------------------------------ 124ms Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces system playlist update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Ignoring MPD Status Update Jul 17 10:30:06 idsd-phantom volumio[1119]: info: Jul 17 10:30:06 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:06 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:06 idsd-phantom volumio[1119]: info: ------------------------------ 22ms Jul 17 10:30:06 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:06 idsd-phantom demo[720]: format: DSD Jul 17 10:30:06 idsd-phantom demo[720]: base: 0 times: 1 Jul 17 10:30:06 idsd-phantom demo[720]: in rate: 44100 Jul 17 10:30:06 idsd-phantom demo[720]: base: 0 times: 12 Jul 17 10:30:06 idsd-phantom demo[720]: out rate: 90316800 Jul 17 10:30:06 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:06 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:06 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:06 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:06 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:30:06 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:06 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:30:06 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:06 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:06 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:06 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 01 02 0c 04 01 08 1f 03 0f 1e f7 Jul 17 10:30:06 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand status took 918 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Jul 17 10:30:07 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ------------------------------ 913ms Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand status took 911 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"（左声道）单身情歌---（右声道）单眼皮女生","artist":"杨之正荣誉出品","album":null,"uri":"NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3","trackType":"mp3"} Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ------------------------------ 989ms Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Jul 17 10:30:07 idsd-phantom volumio[1119]: ---------------------------- MPD announces state update: player Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::getState Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand status Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand status took 65 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 60 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand status took 4 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioGetState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":60,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"（左声道）单身情歌---（右声道）单眼皮女生","artist":"杨之正荣誉出品","album":null,"uri":"NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3","trackType":"mp3"} Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ------------------------------ 1031ms Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: ControllerMpd::parseTrackInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":60,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"（左声道）单身情歌---（右声道）单眼皮女生","artist":"杨之正荣誉出品","album":null,"uri":"NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3","trackType":"mp3"} Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ControllerMpd::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::servicePushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":60,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"（左声道）单身情歌---（右声道）单眼皮女生","artist":"杨之正荣誉出品","album":null,"uri":"NAS/192.168.1.220/Roon Imports/KAYH/imported at 2022-07-09T09-25 UTC_08_00/歌曲_左声道_单身情歌---_右声道_单眼皮女生.mp3","trackType":"mp3"} Jul 17 10:30:07 idsd-phantom volumio[1119]: verbose: CURRENT POSITION 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState stateService play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::syncState currentStatus play Jul 17 10:30:07 idsd-phantom volumio[1119]: info: Received an update from plugin. extracting info from payload Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreStateMachine::pushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CorePlayQueue::getTrack 39 Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::volumioPushState Jul 17 10:30:07 idsd-phantom volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ------------------------------ 207ms Jul 17 10:30:07 idsd-phantom volumio[1119]: info: ------------------------------ 144ms Jul 17 10:30:07 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:07 idsd-phantom demo[720]: format: DSD Jul 17 10:30:07 idsd-phantom demo[720]: base: 0 times: 1 Jul 17 10:30:07 idsd-phantom demo[720]: in rate: 44100 Jul 17 10:30:07 idsd-phantom demo[720]: base: 0 times: 12 Jul 17 10:30:07 idsd-phantom demo[720]: out rate: 90316800 Jul 17 10:30:07 idsd-phantom demo[720]: current filter index: 2 Jul 17 10:30:07 idsd-phantom demo[720]: current k2 index: 0 Jul 17 10:30:07 idsd-phantom demo[720]: current dsd index: 3 Jul 17 10:30:07 idsd-phantom demo[720]: current filter bit mask: 0x4 Jul 17 10:30:07 idsd-phantom demo[720]: available filter bit mask: 0x1f Jul 17 10:30:07 idsd-phantom demo[720]: current k2 bit mask: 0x1 Jul 17 10:30:07 idsd-phantom demo[720]: available k2 bit mask: 0x5 Jul 17 10:30:07 idsd-phantom demo[720]: current dsd remaster bit mask: 0x8 Jul 17 10:30:07 idsd-phantom demo[720]: available dsd remaster bit mask: 0xf Jul 17 10:30:07 idsd-phantom demo[720]: *************************************************** Jul 17 10:30:07 idsd-phantom demo[720]: [receive from xmos]: ff 05 00 0c 20 01 02 0c 04 01 08 1f 03 0f 1e f7 Jul 17 10:30:07 idsd-phantom demo[720]: STREAM_INFO MESSAGE Jul 17 10:30:08 idsd-phantom volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 10:30:08 idsd-phantom volumio[1119]: Error: connect ETIMEDOUT 173.252.105.21:80 Jul 17 10:30:08 idsd-phantom volumio[1119]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 17 10:30:08 idsd-phantom volumio[1119]: errno: -110, Jul 17 10:30:08 idsd-phantom volumio[1119]: code: 'ETIMEDOUT', Jul 17 10:30:08 idsd-phantom volumio[1119]: syscall: 'connect', Jul 17 10:30:08 idsd-phantom volumio[1119]: address: '173.252.105.21', Jul 17 10:30:08 idsd-phantom volumio[1119]: port: 80 Jul 17 10:30:08 idsd-phantom volumio[1119]: } Jul 17 10:30:08 idsd-phantom volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 10:30:09 idsd-phantom sudo[3190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-17 10:29 Jul 17 10:30:09 idsd-phantom sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="40a76932c19121b7cdc094ceed930d3148ec86d6" VOLUMIO_FE_VERSION="0548e367fe44b194ccf1306c8af35deb040eccd2" VOLUMIO_FE3_VERSION="c63cc070c36379a8d79dc0d7d4a24908bebc2472" VOLUMIO_BE_VERSION="6f588095361a987e2a4b1bc0f9d8a8caeaebe594" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2025年 06月 26日 星期四 14:54:54 CST" VOLUMIO_VERSION="2.01.0" VOLUMIO_HARDWARE="idsd-phantom" VOLUMIO_DEVICENAME="iDSD PHANTOM" VOLUMIO_HASH="502640243a3bc71bb94097d6dba62248"