-- Logs begin at Fri 2025-02-28 14:19:19 UTC, end at Fri 2025-02-28 14:22:40 UTC. -- Feb 28 14:21:00 volumio kernel: scsi 6:0:0:0: Direct-Access Kingston DataTraveler 3.0 PMAP PQ: 0 ANSI: 6 Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: Attached scsi generic sg1 type 0 Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: [sdb] 484442112 512-byte logical blocks: (248 GB/231 GiB) Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: [sdb] Write Protect is off Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: [sdb] Mode Sense: 45 00 00 00 Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Feb 28 14:21:00 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:00 volumio kernel: sdb: sdb1 Feb 28 14:21:00 volumio kernel: sd 6:0:0:0: [sdb] Attached SCSI removable disk Feb 28 14:21:00 volumio volumio[531]: info: Mounting Device Volumio数播 Feb 28 14:21:00 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Volumio数播 -o noatime Feb 28 14:21:00 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 14:21:01 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root Feb 28 14:21:03 volumio volumio[531]: info: UPDATER: Scheduling automatic update Feb 28 14:21:03 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Feb 28 14:21:03 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Feb 28 14:21:03 volumio volumio[531]: info: UPDATER: Auto update will take place at: Sat Mar 01 2025 03:33:53 GMT+0000 (Coordinated Universal Time) Feb 28 14:21:04 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:06 volumio kernel: usb 2-1.1: USB disconnect, device number 10 Feb 28 14:21:06 volumio thd[356]: Error reading device '/dev/input/event2' Feb 28 14:21:08 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:10 volumio kernel: usb 2-1.1: new full-speed USB device number 12 using ehci-pci Feb 28 14:21:10 volumio kernel: usb 2-1.1: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Feb 28 14:21:10 volumio kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 28 14:21:10 volumio kernel: usb 2-1.1: Product: USB2IIC_CTP_CONTROL Feb 28 14:21:10 volumio kernel: usb 2-1.1: Manufacturer: wch.cn Feb 28 14:21:10 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1/2-1.1:1.0/0003:1A86:E5E3.0007/input/input16 Feb 28 14:21:10 volumio kernel: hid-multitouch 0003:1A86:E5E3.0007: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:1d.0-1.1/input0 Feb 28 14:21:10 volumio systemd-udevd[1303]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 14:21:10 volumio systemd-udevd[1304]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 14:21:10 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:21:10 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:12 volumio kernel: usb 2-1.1: USB disconnect, device number 12 Feb 28 14:21:12 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:12 volumio kernel: usb 2-1.1: new full-speed USB device number 13 using ehci-pci Feb 28 14:21:13 volumio kernel: usb 2-1.1: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Feb 28 14:21:13 volumio kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 28 14:21:13 volumio kernel: usb 2-1.1: Product: USB2IIC_CTP_CONTROL Feb 28 14:21:13 volumio kernel: usb 2-1.1: Manufacturer: wch.cn Feb 28 14:21:13 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1/2-1.1:1.0/0003:1A86:E5E3.0008/input/input17 Feb 28 14:21:13 volumio kernel: hid-multitouch 0003:1A86:E5E3.0008: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:1d.0-1.1/input0 Feb 28 14:21:13 volumio systemd-udevd[1303]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 14:21:13 volumio systemd-udevd[1304]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 14:21:16 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand rescan Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: Feb 28 14:21:20 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand rescan took 2 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:20 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: No code Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: ------------------------------ 30ms Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: Feb 28 14:21:20 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: Feb 28 14:21:20 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: Feb 28 14:21:20 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 8 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 8 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 7 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 8 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 8 milliseconds Feb 28 14:21:20 volumio volumio[531]: info: sendMpdCommand status took 7 milliseconds Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:20 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:20 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: No code Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:20 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: No code Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:20 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: No code Feb 28 14:21:20 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:20 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:20 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:20 volumio volumio[531]: info: ------------------------------ 84ms Feb 28 14:21:20 volumio volumio[531]: info: ------------------------------ 83ms Feb 28 14:21:20 volumio volumio[531]: info: ------------------------------ 84ms Feb 28 14:21:21 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:21:21 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:24 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:28 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:29 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: Feb 28 14:21:29 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 98 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 98 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 97 milliseconds Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 138ms Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 138ms Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 180 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 180 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 180 milliseconds Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 216ms Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 216ms Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 246 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 246 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 246 milliseconds Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 293ms Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 293ms Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 325 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 325 milliseconds Feb 28 14:21:29 volumio volumio[531]: info: sendMpdCommand status took 325 milliseconds Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:29 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: No code Feb 28 14:21:29 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:29 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:29 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 361ms Feb 28 14:21:29 volumio volumio[531]: info: ------------------------------ 361ms Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand rescan Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand rescan took 1 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 0 milliseconds Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:32 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: No code Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: ------------------------------ 25ms Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 3 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:32 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:32 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: No code Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:32 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: No code Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:32 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: No code Feb 28 14:21:32 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:32 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:32 volumio volumio[531]: info: ------------------------------ 72ms Feb 28 14:21:32 volumio volumio[531]: info: ------------------------------ 71ms Feb 28 14:21:32 volumio volumio[531]: info: ------------------------------ 72ms Feb 28 14:21:32 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:32 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: Feb 28 14:21:32 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:32 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:32 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 89 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 89 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 88 milliseconds Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 126ms Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 127ms Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 164 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 164 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 163 milliseconds Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 199ms Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 200ms Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 229 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 229 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 229 milliseconds Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 261ms Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 261ms Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 319 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 319 milliseconds Feb 28 14:21:33 volumio volumio[531]: info: sendMpdCommand status took 319 milliseconds Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:33 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: No code Feb 28 14:21:33 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:33 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:33 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 353ms Feb 28 14:21:33 volumio volumio[531]: info: ------------------------------ 354ms Feb 28 14:21:36 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:40 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:44 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:21:44 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:44 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:48 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand rescan Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: Feb 28 14:21:50 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand rescan took 2 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:50 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: No code Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: ------------------------------ 25ms Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: Feb 28 14:21:50 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: Feb 28 14:21:50 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: Feb 28 14:21:50 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 3 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 1 milliseconds Feb 28 14:21:50 volumio volumio[531]: info: sendMpdCommand status took 2 milliseconds Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: Command Router : Notfying DB Updatetrue Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:50 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:50 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: No code Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:50 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: No code Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:50 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: No code Feb 28 14:21:50 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:50 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:50 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:50 volumio volumio[531]: info: ------------------------------ 76ms Feb 28 14:21:50 volumio volumio[531]: info: ------------------------------ 75ms Feb 28 14:21:50 volumio volumio[531]: info: ------------------------------ 74ms Feb 28 14:21:51 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: MPD Database updated - AlbumList cache refreshed Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: database Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: Feb 28 14:21:51 volumio volumio[531]: ---------------------------- MPD announces state update: update Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 92 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 91 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 90 milliseconds Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 126ms Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 126ms Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 154 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 154 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 153 milliseconds Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 188ms Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 189ms Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 215 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 214 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 214 milliseconds Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 250ms Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 249ms Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 314 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 313 milliseconds Feb 28 14:21:51 volumio volumio[531]: info: sendMpdCommand status took 314 milliseconds Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: Command Router : Notfying DB Updatefalse Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::Close All Modals sent Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 14:21:51 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState stateService stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: No code Feb 28 14:21:51 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:21:51 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:21:51 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 378ms Feb 28 14:21:51 volumio volumio[531]: info: ------------------------------ 377ms Feb 28 14:21:55 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:21:55 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:06 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:22:06 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: Preload queue cleared Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::ClearQueue Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::stop Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::clearPlayQueue Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::saveQueue Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushQueue Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::addQueueItems Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::addQueueItems Feb 28 14:22:07 volumio volumio[531]: info: Preload queue cleared Feb 28 14:22:07 volumio volumio[531]: info: Adding Item to queue: music-library/USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac Feb 28 14:22:07 volumio volumio[531]: info: Exploding uri music-library/USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac in service mpd Feb 28 14:22:07 volumio volumio[531]: info: ALBUMART /albumart?cacheid=858&web=%E5%BC%A0%E7%8E%AE%E4%BC%BD/%E6%97%A0%E6%83%85%E4%BC%A4/large&path=%2Fmnt%2FUSB%2FVolumio%E6%95%B0%E6%92%AD%2FHi%20Fi%20%E4%BA%BA%E5%A3%B0%2F%E5%BC%A0%E7%8E%AE%E4%BC%BD%20%E6%97%A0%E6%83%85%E4%BC%A4%2F%E5%BC%A0%E7%8E%AE%E4%BC%BD-%E5%A5%B3%E4%BA%BA%E5%A6%82%E7%83%9F.flac&metadata=false Feb 28 14:22:07 volumio volumio[531]: info: URI /mnt/USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushQueue Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::saveQueue Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::updateTrackBlock Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrackBlock Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPlay Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::play index 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::stop Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::play index undefined Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::startPlaybackTimer Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::clearAddPlayTracks USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand stop took 2 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand clear took 0 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand add "USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac" Feb 28 14:22:07 volumio volumio[531]: error: updateQueue error: null Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 2ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand add "USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac" took 1 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand play Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 3ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand play took 3 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 2ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 1ms Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces system playlist update Feb 28 14:22:07 volumio volumio[531]: info: Ignoring MPD Status Update Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: error: Upnp client error: Error: This socket has been ended by the other party Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 57 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 58ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 57 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 57ms Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 59 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 48ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 48 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: info: Feb 28 14:22:07 volumio volumio[531]: ---------------------------- MPD announces state update: player Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::getState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand status Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 4 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 4 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus stop Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 70ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 71ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 19 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand status took 18 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseState Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":204,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":318,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":660,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 110ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 98ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 53ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 53ms Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 28 14:22:07 volumio volumio[531]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: verbose: ControllerMpd::parseTrackInfo Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ControllerMpd::pushState Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::servicePushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"女人如烟","artist":"张玮伽","album":"无情伤","uri":"USB/Volumio数播/Hi Fi 人声/张玮伽 无情伤/张玮伽-女人如烟.flac","trackType":"flac"} Feb 28 14:22:07 volumio volumio[531]: verbose: CURRENT POSITION 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState stateService play Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::syncState currentStatus play Feb 28 14:22:07 volumio volumio[531]: info: Received an update from plugin. extracting info from payload Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: CoreStateMachine::pushState Feb 28 14:22:07 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:07 volumio volumio[531]: info: CoreCommandRouter::volumioPushState Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 67ms Feb 28 14:22:07 volumio volumio[531]: info: ------------------------------ 65ms Feb 28 14:22:17 volumio volumio[531]: info: CoreCommandRouter::volumioGetState Feb 28 14:22:17 volumio volumio[531]: info: CorePlayQueue::getTrack 0 Feb 28 14:22:19 volumio screensaver.sh[350]: pygame 1.9.4.post1 Feb 28 14:22:19 volumio screensaver.sh[350]: Hello from the pygame community. https://www.pygame.org/contribute.html Feb 28 14:22:40 volumio volumio[531]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 14:22:40 volumio volumio[531]: Error: connect ETIMEDOUT 66.220.146.94:443 Feb 28 14:22:40 volumio volumio[531]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 28 14:22:40 volumio volumio[531]: errno: -110, Feb 28 14:22:40 volumio volumio[531]: code: 'ETIMEDOUT', Feb 28 14:22:40 volumio volumio[531]: syscall: 'connect', Feb 28 14:22:40 volumio volumio[531]: address: '66.220.146.94', Feb 28 14:22:40 volumio volumio[531]: port: 443 Feb 28 14:22:40 volumio volumio[531]: } Feb 28 14:22:40 volumio volumio[531]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 14:22:40 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 14:21 Feb 28 14:22:40 volumio sudo[1508]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:13:59 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6a6571e2ab129da64c127254a7a779b9"