Aug 28 10:47:02 volumio volumio[7586]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 28 10:47:07 volumio volumio[7586]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 28 10:47:08 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 10:47:08 volumio volumio[7586]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 28 10:47:08 volumio volumio[7586]: info: CoreCommandRouter::volumioGetState Aug 28 10:47:08 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:11 volumio kernel: usb 3-2: USB disconnect, device number 4 Aug 28 10:47:11 volumio volumio[7586]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:1d.7/usb3/3-2/3-2:1.0/host7/target7:0:0/7:0:0:0/block/sdb/sdb1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/pci-0000:00:1d.7-usb-0:2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/38AB-A14C /dev/disk/by-id/usb-Lenovo_SX7_64G_23041793010116-0:0-part1 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-partuuid/8ff80263-86d3-4261-911f-d93a3adaa9e1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/pci0000:00/0000:00:1d.7/usb3/3-2/3-2:1.0/host7/target7:0:0/7:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"11","ID_BUS":"usb","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"38AB-A14C","ID_FS_UUID_ENC":"38AB-A14C","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"SX7_64G","ID_MODEL_ENC":"SX7\\x2064G\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"38ac","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"123697119","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"8ff80263-86d3-4261-911f-d93a3adaa9e1","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"37b96f7b-c797-43f8-816b-5687d681078f","ID_PATH":"pci-0000:00:1d.7-usb-0:2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_1d_7-usb-0_2_1_0-scsi-0_0_0_0","ID_REVISION":"8.07","ID_SERIAL":"Lenovo_SX7_64G_23041793010116-0:0","ID_SERIAL_SHORT":"23041793010116","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"SX7_64G","ID_USB_MODEL_ENC":"SX7\\x2064G\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"38ac","ID_USB_REVISION":"8.07","ID_USB_SERIAL":"Lenovo_SX7_64G_23041793010116-0:0","ID_USB_SERIAL_SHORT":"23041793010116","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Lenovo","ID_USB_VENDOR_ENC":"Lenovo\\x20\\x20","ID_USB_VENDOR_ID":"17ef","ID_VENDOR":"Lenovo","ID_VENDOR_ENC":"Lenovo\\x20\\x20","ID_VENDOR_ID":"17ef","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Basic data partition","SEQNUM":"3404","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"165390339"} Aug 28 10:47:11 volumio sudo[8149]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/umount -f /dev/sdb1 Aug 28 10:47:11 volumio sudo[8149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 10:47:11 volumio systemd[1]: media-38AB\x2dA14C.mount: Deactivated successfully. Aug 28 10:47:11 volumio sudo[8149]: pam_unix(sudo:session): session closed for user root Aug 28 10:47:12 volumio volumio[7586]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 28 10:47:12 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 10:47:12 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 10:47:12 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 10:47:13 volumio volumio-remote-updater[804]: Test mode disabled Aug 28 10:47:13 volumio volumio-remote-updater[804]: Alpha mode disabled Aug 28 10:47:13 volumio volumio-remote-updater[804]: Alpha legacy test mode disabled Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 28 10:47:13 volumio volumio[7586]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 10:47:13 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 10:47:14 volumio volumio[7586]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 28 10:47:14 volumio volumio[7586]: info: Completed starting MyVolumio Plugin Aug 28 10:47:14 volumio volumio[7586]: [Metrics] CommandRouter: 34s 625.57ms Aug 28 10:47:14 volumio volumio[7586]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 10:47:14 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 10:47:14 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 10:47:14 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:14 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:15 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 28 10:47:15 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 10:47:15 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 28 10:47:15 volumio volumio[7586]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/38AB-A14C Aug 28 10:47:16 volumio volumio[7586]: info: Scanning removed location : "USB/38AB-A14C" Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 7 milliseconds Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:16 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: Aug 28 10:47:16 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 389 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 389 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 388 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 12 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 11 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 11 milliseconds Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 515ms Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 555ms Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 177ms Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 178ms Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 212 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 210 milliseconds Aug 28 10:47:16 volumio volumio[7586]: info: sendMpdCommand status took 210 milliseconds Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: 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} Aug 28 10:47:16 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: No code Aug 28 10:47:16 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:16 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:16 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 283ms Aug 28 10:47:16 volumio volumio[7586]: info: ------------------------------ 283ms Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:16 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:17 volumio volumio[7586]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Aug 28 10:47:21 volumio volumio[7586]: info: BOOT COMPLETED Aug 28 10:47:22 volumio volumio[7586]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = ready Aug 28 10:47:22 volumio volumio[7586]: info: AutoStart - System ready state CONFIRMED after 9 checks Aug 28 10:47:22 volumio volumio[7586]: info: AutoStart - Applying additional delay of 5000ms before playback Aug 28 10:47:27 volumio volumio[7586]: info: AutoStart - startPlayback called Aug 28 10:47:27 volumio volumio[7586]: info: CoreCommandRouter::volumioGetQueue Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::getQueue Aug 28 10:47:27 volumio volumio[7586]: info: CorePlayQueue::getQueue Aug 28 10:47:27 volumio volumio[7586]: info: AutoStart - Queue has 4165 items Aug 28 10:47:27 volumio volumio[7586]: info: AutoStart - Playing from position 0 Aug 28 10:47:27 volumio volumio[7586]: info: CoreCommandRouter::volumioPlay Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::play index 0 Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::stop Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::play index undefined Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 10:47:27 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:27 volumio volumio[7586]: info: CoreStateMachine::startPlaybackTimer Aug 28 10:47:27 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:27 volumio volumio[7586]: verbose: ControllerMpd::clearAddPlayTracks USB/38AB-A14C/up/2025年一切随心愿.mp3 Aug 28 10:47:27 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 10:47:27 volumio volumio[7586]: info: sendMpdCommand stop took 1 milliseconds Aug 28 10:47:27 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 10:47:27 volumio volumio[7586]: info: Aug 28 10:47:27 volumio volumio[7586]: ---------------------------- MPD announces system playlist update Aug 28 10:47:27 volumio volumio[7586]: info: Ignoring MPD Status Update Aug 28 10:47:27 volumio volumio[7586]: info: sendMpdCommand clear took 1 milliseconds Aug 28 10:47:27 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand add "USB/38AB-A14C/up/2025年一切随心愿.mp3" Aug 28 10:47:27 volumio volumio[7586]: info: Aug 28 10:47:27 volumio volumio[7586]: ---------------------------- MPD announces system playlist update Aug 28 10:47:27 volumio volumio[7586]: info: Ignoring MPD Status Update Aug 28 10:47:27 volumio volumio[7586]: error: updateQueue error: null Aug 28 10:47:27 volumio volumio[7586]: error: updateQueue error: null Aug 28 10:47:27 volumio volumio[7586]: info: ------------------------------ 3ms Aug 28 10:47:27 volumio volumio[7586]: info: ------------------------------ 2ms Aug 28 10:47:34 volumio kernel: usb 3-2: new high-speed USB device number 5 using ehci-pci Aug 28 10:47:35 volumio kernel: usb 3-2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Aug 28 10:47:35 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 28 10:47:35 volumio kernel: usb 3-2: Product: Mass Storage Device Aug 28 10:47:35 volumio kernel: usb 3-2: Manufacturer: Generic Aug 28 10:47:35 volumio kernel: usb 3-2: SerialNumber: 121220160204 Aug 28 10:47:35 volumio kernel: usb-storage 3-2:1.0: USB Mass Storage device detected Aug 28 10:47:35 volumio kernel: scsi host7: usb-storage 3-2:1.0 Aug 28 10:47:36 volumio kernel: scsi 7:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: Attached scsi generic sg1 type 0 Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] 15278080 512-byte logical blocks: (7.82 GB/7.29 GiB) Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] Write Protect is off Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] Mode Sense: 03 00 00 00 Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] No Caching mode page found Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] Assuming drive cache: write through Aug 28 10:47:36 volumio kernel: sdb: sdb1 Aug 28 10:47:36 volumio kernel: sd 7:0:0:0: [sdb] Attached SCSI removable disk Aug 28 10:47:36 volumio volumio[7586]: info: Mounting Device 系统 Aug 28 10:47:36 volumio sudo[8320]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mount /dev/sdb1 /mnt/USB/系统 -o noatime Aug 28 10:47:36 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 10:47:36 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Aug 28 10:47:36 volumio volumio[7586]: info: Scanning new location : "USB/系统" Aug 28 10:47:36 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:36 volumio volumio[7586]: info: Aug 28 10:47:36 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:36 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:36 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:36 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:36 volumio volumio[7586]: info: Aug 28 10:47:36 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:36 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:36 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:36 volumio volumio[7586]: info: sendMpdCommand status took 9 milliseconds Aug 28 10:47:36 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 423 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 421 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 419 milliseconds Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: 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} Aug 28 10:47:37 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: No code Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: 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} Aug 28 10:47:37 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: No code Aug 28 10:47:37 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:37 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:37 volumio volumio[7586]: info: ------------------------------ 483ms Aug 28 10:47:37 volumio volumio[7586]: info: ------------------------------ 500ms Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:37 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:37 volumio volumio[7586]: info: Aug 28 10:47:37 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: Aug 28 10:47:37 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:37 volumio volumio[7586]: info: Aug 28 10:47:37 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: Aug 28 10:47:37 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 7 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 7 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 7 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 6 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:47:37 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:47:37 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:37 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:38 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: 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} Aug 28 10:47:38 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: No code Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: 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} Aug 28 10:47:38 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: No code Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: 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} Aug 28 10:47:38 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: No code Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: 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} Aug 28 10:47:38 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: No code Aug 28 10:47:38 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:38 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:38 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:38 volumio volumio[7586]: info: ------------------------------ 176ms Aug 28 10:47:38 volumio volumio[7586]: info: ------------------------------ 177ms Aug 28 10:47:38 volumio volumio[7586]: info: ------------------------------ 177ms Aug 28 10:47:38 volumio volumio[7586]: info: ------------------------------ 177ms Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:38 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:46 volumio volumio[7586]: info: CoreCommandRouter::volumioGetState Aug 28 10:47:46 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: info: Retrieving Cloud Streaming UI Aug 28 10:47:48 volumio volumio[7586]: info: Getting Tidal Cloud Configuration Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: info: Getting Qobuz Cloud Configuration Aug 28 10:47:48 volumio volumio[7586]: info: Asking plugin for UI Config Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: info: Getting Spotify Cloud Configuration Aug 28 10:47:48 volumio volumio[7586]: info: Asking plugin for UI Config Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: info: Saving Spotify Acccount Aug 28 10:47:48 volumio volumio[7586]: info: Got Tidal Cloud Configuration Aug 28 10:47:48 volumio volumio[7586]: info: Got it Aug 28 10:47:48 volumio volumio[7586]: info: Got it Aug 28 10:47:48 volumio volumio[7586]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 10:47:48 volumio volumio[7586]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 10:47:48 volumio volumio[7586]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 10:47:49 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 10:47:49 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:51 volumio volumio[7586]: info: Aug 28 10:47:51 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:51 volumio volumio[7586]: info: sendMpdCommand rescan took 4 milliseconds Aug 28 10:47:51 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:51 volumio volumio[7586]: info: Aug 28 10:47:51 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:51 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:51 volumio volumio[7586]: info: sendMpdCommand status took 2 milliseconds Aug 28 10:47:51 volumio volumio[7586]: info: sendMpdCommand status took 1 milliseconds Aug 28 10:47:51 volumio volumio[7586]: info: sendMpdCommand status took 1 milliseconds Aug 28 10:47:51 volumio volumio[7586]: info: sendMpdCommand status took 0 milliseconds Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:51 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:51 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:51 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:51 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: 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} Aug 28 10:47:51 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: No code Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: 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} Aug 28 10:47:51 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: No code Aug 28 10:47:51 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:51 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:51 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:51 volumio volumio[7586]: info: ------------------------------ 208ms Aug 28 10:47:51 volumio volumio[7586]: info: ------------------------------ 207ms Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:51 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:53 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 10:47:54 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 10:47:54 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 10:47:54 volumio volumio[7586]: info: sendMpdCommand rescan took 3 milliseconds Aug 28 10:47:55 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:55 volumio volumio[7586]: info: Aug 28 10:47:55 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: Aug 28 10:47:55 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:55 volumio volumio[7586]: info: Aug 28 10:47:55 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: Aug 28 10:47:55 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 5 milliseconds Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: 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} Aug 28 10:47:55 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: No code Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: 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} Aug 28 10:47:55 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: No code Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: ------------------------------ 177ms Aug 28 10:47:55 volumio volumio[7586]: info: ------------------------------ 180ms Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 183 milliseconds Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 183 milliseconds Aug 28 10:47:55 volumio volumio[7586]: info: sendMpdCommand status took 183 milliseconds Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: 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} Aug 28 10:47:55 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: No code Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: 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} Aug 28 10:47:55 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: No code Aug 28 10:47:55 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:55 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:55 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:55 volumio volumio[7586]: info: ------------------------------ 310ms Aug 28 10:47:55 volumio volumio[7586]: info: ------------------------------ 310ms Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:55 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:56 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 28 10:47:56 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand rescan Aug 28 10:47:56 volumio volumio[7586]: info: sendMpdCommand rescan took 1 milliseconds Aug 28 10:47:57 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 28 10:47:59 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:59 volumio volumio[7586]: info: Aug 28 10:47:59 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: Aug 28 10:47:59 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:47:59 volumio volumio[7586]: info: Aug 28 10:47:59 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: Aug 28 10:47:59 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 3 milliseconds Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 3 milliseconds Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: 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} Aug 28 10:47:59 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: No code Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: 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} Aug 28 10:47:59 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: No code Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: ------------------------------ 193ms Aug 28 10:47:59 volumio volumio[7586]: info: ------------------------------ 194ms Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 205 milliseconds Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 205 milliseconds Aug 28 10:47:59 volumio volumio[7586]: info: sendMpdCommand status took 205 milliseconds Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: info: Command Router : Notfying DB Updatetrue Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: 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} Aug 28 10:47:59 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: No code Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: 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} Aug 28 10:47:59 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: No code Aug 28 10:47:59 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:47:59 volumio volumio[7586]: info: ------------------------------ 372ms Aug 28 10:47:59 volumio volumio[7586]: info: ------------------------------ 372ms Aug 28 10:47:59 volumio volumio[7586]: info: CoreCommandRouter::volumioGetState Aug 28 10:47:59 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:47:59 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:48:03 volumio volumio[7586]: info: Aug 28 10:48:03 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: Aug 28 10:48:03 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: MPD Database updated - AlbumList cache refreshed Aug 28 10:48:03 volumio volumio[7586]: info: Aug 28 10:48:03 volumio volumio[7586]: ---------------------------- MPD announces state update: database Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: Aug 28 10:48:03 volumio volumio[7586]: ---------------------------- MPD announces state update: update Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::getState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::sendMpdCommand status Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 5 milliseconds Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 4 milliseconds Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: 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} Aug 28 10:48:03 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: No code Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: 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} Aug 28 10:48:03 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: No code Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: ------------------------------ 201ms Aug 28 10:48:03 volumio volumio[7586]: info: ------------------------------ 204ms Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 206 milliseconds Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 205 milliseconds Aug 28 10:48:03 volumio volumio[7586]: info: sendMpdCommand status took 206 milliseconds Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: info: Command Router : Notfying DB Updatefalse Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::Close All Modals sent Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: verbose: ControllerMpd::parseState Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: 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} Aug 28 10:48:03 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: No code Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: ControllerMpd::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::servicePushState Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: 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} Aug 28 10:48:03 volumio volumio[7586]: verbose: CURRENT POSITION 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState stateService stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::syncState currentStatus stop Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: No code Aug 28 10:48:03 volumio volumio[7586]: info: CoreStateMachine::pushState Aug 28 10:48:03 volumio volumio[7586]: info: CorePlayQueue::getTrack 0 Aug 28 10:48:03 volumio volumio[7586]: info: CoreCommandRouter::volumioPushState Aug 28 10:48:03 volumio volumio[7586]: info: ------------------------------ 262ms Aug 28 10:48:03 volumio volumio[7586]: info: ------------------------------ 262ms Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:03 volumio volumio[7586]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=false Aug 28 10:48:06 volumio volumio[7586]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 28 10:48:06 volumio volumio[7586]: info: CURURI: music-library/USB Aug 28 10:48:06 volumio volumio[7586]: info: Preload queue cleared Aug 28 10:48:08 volumio volumio[7586]: info: Preload queue cleared Aug 28 10:48:08 volumio volumio[7586]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 10:48:08 volumio volumio[7586]: info: CoreStateMachine::ClearQueue Aug 28 10:48:08 volumio volumio[7586]: info: CoreStateMachine::stop Aug 28 10:48:08 volumio volumio[7586]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 10:48:08 volumio volumio[7586]: info: CorePlayQueue::clearPlayQueue Aug 28 10:48:08 volumio volumio[7586]: info: CorePlayQueue::saveQueue Aug 28 10:48:08 volumio volumio[7586]: info: CoreCommandRouter::volumioPushQueue Aug 28 10:48:08 volumio volumio[7586]: info: CoreStateMachine::addQueueItems Aug 28 10:48:08 volumio volumio[7586]: info: CorePlayQueue::addQueueItems Aug 28 10:48:08 volumio volumio[7586]: info: Preload queue cleared Aug 28 10:48:08 volumio volumio[7586]: info: Adding Item to queue: music-library/USB/系统 Aug 28 10:48:08 volumio volumio[7586]: info: Exploding uri music-library/USB/系统 in service mpd Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F03%20%E7%A4%BE%E5%BA%86%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/03 社庆 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F05%20%E5%A4%BA%E4%B8%B0%E6%94%B6%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/05 夺丰收 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F08%20%E9%9D%92%E5%A4%A9%E6%AD%8C%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/08 青天歌 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F09%20%E5%96%9C%E5%BC%80%E9%95%B0%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/09 喜开镰 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F10%20%E8%AF%84%E6%88%8F%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/10 评戏 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F11%20%E6%B7%98%E9%87%91%E4%BB%A4%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/11 淘金令 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F12%20%E5%AE%B6%E4%B9%A1%E6%83%85%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97(1).mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/12 家乡情 唢呐独奏 郭雅志(1).mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%B9%C3%B9%C3%91%C3%85%C3%96%C2%BE/%C2%B0%C3%99%C3%84%C3%B1%C2%B3%C2%AF%C2%B7%C3%AF%20%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0%C2%BE%C2%AB%C3%8B%C3%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F13%20%E9%80%81%E6%96%B0%E5%A8%98%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%AD%E9%9B%85%E5%BF%97.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/13 送新娘 唢呐独奏 郭雅志.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E4%B8%80%E6%9E%9D%E8%8A%B1%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/一枝花 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E4%B9%A1%E9%9F%B3%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/乡音 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E5%85%A8%E5%AE%B6%E7%A6%8F%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/全家福 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E5%96%9C%E5%BC%80%E9%95%B0%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/喜开镰 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E5%96%9C%E8%BF%8E%E6%98%A5%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/喜迎春 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E5%B1%B1%E4%B8%9C%E5%A4%A7%E9%BC%93%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/山东大鼓 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E5%B7%9D%E6%B1%9F%E6%83%85%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/川江情 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C3%9F%C3%AF%C3%84%C3%85/%C3%9F%C3%AF%C3%84%C3%85%20(%C3%96%C3%90%C2%B9%C3%BA%C3%83%C3%B1%C3%97%C3%A5%C3%86%C3%B7%C3%80%C3%96%C2%B6%C3%80%C3%97%C3%A0%C3%87%C3%BA%C2%BC%C2%AF)/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E6%8A%AC%E8%8A%B1%E8%BD%BF%20Raising%20The%20Bridal%20Seda%20%E7%8B%AC%E5%A5%8F%20%E5%94%A2%E5%91%90.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/抬花轿 Raising The Bridal Seda 独奏 唢呐.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E6%AD%A3%E6%9C%88%E5%8D%81%E4%BA%94%E9%97%B9%E9%9B%AA%E7%81%AF%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/正月十五闹雪灯 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E6%B2%B3%E5%8C%97%E6%A2%86%E5%AD%90//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E6%B2%B3%E5%8C%97%E6%A2%86%E5%AD%90-%E5%94%A2%E5%91%90.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/河北梆子-唢呐.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=(%C3%9F%C3%AF%C3%84%C3%85%C2%B6%C3%80%C3%97%C3%A0)/%C3%96%C3%90%C2%BB%C2%AA%C3%89%C3%B1%C3%94%C3%8F%20%C3%83%C3%B1%C3%80%C3%96%C2%B4%C3%B3%C3%88%C2%AB-1-%C2%B4%C2%BA%C2%BD%C2%AD%C2%BB%C2%A8%C3%94%C3%82%C3%92%C2%B9/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E7%99%BE%E9%B8%9F%E6%9C%9D%E5%87%A4%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/百鸟朝凤 唢呐独奏.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E7%BA%AF%E9%9F%B3%E4%B9%90//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E7%BA%AF%E9%9F%B3%E4%B9%90-%E5%B1%B1%E6%9D%91%E6%9D%A5%E4%BA%86%E5%94%AE%E8%B4%A7%E5%91%98(%E5%94%A2%E5%91%90).mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/纯音乐-山村来了售货员(唢呐).mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E7%BA%AF%E9%9F%B3%E4%B9%90//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E7%BA%AF%E9%9F%B3%E4%B9%90-%E6%89%93%E6%9E%A3(%E5%94%A2%E5%91%90).mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/纯音乐-打枣(唢呐).mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E7%BA%AF%E9%9F%B3%E4%B9%90/%E7%BA%AF%E9%9F%B3%E4%B9%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E7%BA%AF%E9%9F%B3%E4%B9%90-%E8%B5%9B%E9%BE%99%E5%A4%BA%E6%A0%87(%E5%94%A2%E5%91%90).mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/纯音乐-赛龙夺标(唢呐).mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%C2%BA%C3%82%C3%93%C3%B1%C3%86%C3%A7%C3%91%C3%9D%C3%97%C3%A0//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E8%8D%89%E5%8E%9F%E4%B8%8A%E7%9A%84%E7%BA%A2%E5%8D%AB%E5%85%B5%E8%A7%81%E5%88%B0%E4%BA%86%E6%AF%9B%E4%B8%BB%E5%B8%AD%20%E5%94%A2%E5%91%90%E7%8B%AC%E5%A5%8F%20%E9%83%9D%E7%8E%89%E6%AD%A7.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/草原上的红卫兵见到了毛主席 唢呐独奏 郝玉歧.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%9A%8B%E6%99%AF%E5%B1%B1//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E9%9A%8B%E6%99%AF%E5%B1%B1-%E5%A4%A9%E8%B7%AF(%E5%94%A2%E5%91%90%E6%BC%94%E5%A5%8F).mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/隋景山-天路(唢呐演奏).mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%9B%A8%E6%9E%9C%E5%94%B1%E7%89%87/%E4%B8%80%E6%9E%9D%E8%8A%B1/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E9%9B%A8%E6%9E%9C%E5%94%B1%E7%89%87-%E6%AD%A3%E6%9C%88%E5%8D%81%E4%BA%94%E9%97%B9%E5%85%83%E5%AE%B5%5B%E5%94%A2%E5%91%90%5D.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/雨果唱片-正月十五闹元宵[唢呐].mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%E9%84%A2%E7%A3%8A/%E4%B8%AD%E5%9B%BD%E6%B0%91%E4%B9%90%E5%A4%A7%E5%B8%88%E7%BA%AF%E7%8B%AC%E5%A5%8F-%E5%94%A2%E5%91%90/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%94%A2%E5%91%90%2F%E9%BB%84%E5%9C%9F%E6%83%85%20%E5%94%A2%E5%91%90%20%E9%84%A2%E7%A3%8A.mp3&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/唢呐/黄土情 唢呐 鄢磊.mp3 Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%A7%9C%E8%82%B2%E6%81%92%20-%20%E4%BB%80%E4%B9%88%E6%97%B6%E5%80%99%2F%E5%A7%9C%E8%82%B2%E6%81%92%20-%20%E4%BB%80%E4%B9%88%E6%97%B6%E5%80%99.wav&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/姜育恒 - 什么时候/姜育恒 - 什么时候.wav Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%3F%3F%3F//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E3%80%8A%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3%E3%80%8BWAV%2BCUE%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E3%80%8A%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3%E3%80%8BWAV%2BCUE%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%20-%20%E5%A4%A9%E6%B6%AF%E6%AD%8C%E5%A5%B3.wav&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/王维倩《天涯歌女》WAV+CUE/王维倩《天涯歌女》WAV+CUE/王维倩 - 天涯歌女.wav Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=%3F%3F%3F//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E3%80%8A%E9%9D%99%E5%A4%9C%E6%9B%B2.%E7%BE%8E%E4%B8%BD%E7%9A%84%E6%A2%A6%E7%A5%9E%E3%80%8BHQCD%5BWAV%2BCUE%5D%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E3%80%8A%E9%9D%99%E5%A4%9C%E6%9B%B2.%E7%BE%8E%E4%B8%BD%E7%9A%84%E6%A2%A6%E7%A5%9E%E3%80%8BHQCD%5BWAV%2BCUE%5D%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%20-%20%E9%9D%99%E5%A4%9C%E6%9B%B2%20%E7%BE%8E%E4%B8%BD%E7%9A%84%E6%A2%A6%E7%A5%9E%20HQ.wav&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/王维倩《静夜曲.美丽的梦神》HQCD[WAV+CUE]/王维倩《静夜曲.美丽的梦神》HQCD[WAV+CUE]/王维倩 - 静夜曲 美丽的梦神 HQ.wav Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=/%3F%CF%BA%3F%3F%3F%3F%3F1%5B%3F%C2%B8%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%3F%3F%3F%3F15%3F%3F%5D%20ADMS/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E4%B8%8A%E6%B5%B7%E5%BE%80%E4%BA%8B1%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%20%E4%B8%8A%E6%B5%B7%E5%BE%80%E4%BA%8B1%20ADMS(%E9%99%88%E6%AD%8C%E8%BE%9B%E7%BB%8F%E5%85%B8%E5%90%8D%E6%9B%B215%E9%A6%96)%5B%E6%AD%A3%E7%89%88%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2FCDImage.cue&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/王维倩上海往事1/王维倩 上海往事1 ADMS(陈歌辛经典名曲15首)[正版原抓WAV+CUE]/CDImage.cue Aug 28 10:48:08 volumio volumio[7586]: info: ALBUMART /albumart?cacheid=410&web=/%3F%CF%BA%3F%3F%3F%3F%3F1%5B%3F%C2%B8%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%3F%3F%3F%3F15%3F%3F%5D%20ADMS/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%E4%B8%8A%E6%B5%B7%E5%BE%80%E4%BA%8B1%2F%E7%8E%8B%E7%BB%B4%E5%80%A9%20%E4%B8%8A%E6%B5%B7%E5%BE%80%E4%BA%8B1%20ADMS(%E9%99%88%E6%AD%8C%E8%BE%9B%E7%BB%8F%E5%85%B8%E5%90%8D%E6%9B%B215%E9%A6%96)%5B%E6%AD%A3%E7%89%88%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2FCDImage.cue&metadata=true Aug 28 10:48:08 volumio volumio[7586]: info: URI /mnt/USB/系统/王维倩上海往事1/王维倩 上海往事1 ADMS(陈歌辛经典名曲15首)[正版原抓WAV+CUE]/CDImage.cue Aug 28 10:48:08 volumio volumio[7586]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 10:48:08 volumio volumio[7586]: Error: Unable to resolve or reject the same promise twice Aug 28 10:48:08 volumio volumio[7586]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 28 10:48:08 volumio volumio[7586]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 28 10:48:08 volumio volumio[7586]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 28 10:48:08 volumio volumio[7586]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 28 10:48:08 volumio volumio[7586]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 28 10:48:08 volumio volumio[7586]: at Socket.emit (node:events:514:28) Aug 28 10:48:08 volumio volumio[7586]: at addChunk (node:internal/streams/readable:343:12) Aug 28 10:48:08 volumio volumio[7586]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 28 10:48:08 volumio volumio[7586]: at Readable.push (node:internal/streams/readable:253:10) Aug 28 10:48:08 volumio volumio[7586]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 28 10:48:08 volumio volumio[7586]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 10:48:08 volumio sudo[8526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 10:47' Aug 28 10:48:08 volumio sudo[8526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"