-- Logs begin at Mon 2025-04-28 22:15:11 CST, end at Mon 2025-04-28 22:19:52 CST. -- Apr 28 22:18:02 volumio-x86 volumio[1172]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 28 22:18:02 volumio-x86 volumio[1172]: info: Retrying Login Due to Network Error Apr 28 22:18:12 volumio-x86 volumio[1172]: info: MyVolumio login type: Token Apr 28 22:18:12 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioGetState Apr 28 22:18:12 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Retrieving Cloud Streaming UI Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Getting Tidal Cloud Configuration Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Getting Qobuz Cloud Configuration Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Asking plugin for UI Config Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Getting Spotify Cloud Configuration Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Asking plugin for UI Config Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Saving Spotify Acccount Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Got Tidal Cloud Configuration Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Got it Apr 28 22:18:14 volumio-x86 volumio[1172]: info: Got it Apr 28 22:18:14 volumio-x86 volumio[1172]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 22:18:14 volumio-x86 volumio[1172]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:14 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Apr 28 22:18:17 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 1 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125078,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ------------------------------ 34ms Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Apr 28 22:18:17 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Apr 28 22:18:17 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 4 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 4 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 3 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatefalse Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125260,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"940 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125260,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"940 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:18:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:18:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ------------------------------ 39ms Apr 28 22:18:17 volumio-x86 volumio[1172]: info: ------------------------------ 39ms Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:22 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:26 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:30 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:34 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:38 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:42 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:42 volumio-x86 volumio[1172]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 28 22:18:42 volumio-x86 volumio[1172]: info: Retrying Login Due to Network Error Apr 28 22:18:46 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: new SuperSpeed USB device number 4 using xhci_hcd Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: New USB device found, idVendor=174c, idProduct=5106, bcdDevice=80.00 Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: Product: StoreJet Transcend Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: Manufacturer: StoreJet Transcend Apr 28 22:18:47 volumio-x86 kernel: usb 2-5: SerialNumber: S318J9BG130143 Apr 28 22:18:47 volumio-x86 kernel: usb-storage 2-5:1.0: USB Mass Storage device detected Apr 28 22:18:47 volumio-x86 kernel: scsi host6: usb-storage 2-5:1.0 Apr 28 22:18:48 volumio-x86 kernel: scsi 6:0:0:0: Direct-Access StoreJet Transcend 0 PQ: 0 ANSI: 6 Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: Attached scsi generic sg2 type 0 Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: [sdd] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: [sdd] Write Protect is off Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: [sdd] Mode Sense: 43 00 00 00 Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 28 22:18:48 volumio-x86 kernel: sdd: sdd1 Apr 28 22:18:48 volumio-x86 kernel: sd 6:0:0:0: [sdd] Attached SCSI disk Apr 28 22:18:48 volumio-x86 volumio[1172]: info: Mounting Device 系统 Apr 28 22:18:48 volumio-x86 sudo[3083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdd1 /mnt/USB/系统 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 28 22:18:48 volumio-x86 sudo[3083]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 22:18:51 volumio-x86 ntfs-3g[3087]: Version 2017.3.23AR.3 integrated FUSE 28 Apr 28 22:18:51 volumio-x86 ntfs-3g[3087]: Mounted /dev/sdd1 (Read-Write, label "系统", NTFS 3.1) Apr 28 22:18:51 volumio-x86 ntfs-3g[3087]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 28 22:18:51 volumio-x86 ntfs-3g[3087]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdd1,blkdev,blksize=4096 Apr 28 22:18:51 volumio-x86 ntfs-3g[3087]: Global ownership and permissions enforced, configuration type 7 Apr 28 22:18:51 volumio-x86 sudo[3083]: pam_unix(sudo:session): session closed for user root Apr 28 22:18:51 volumio-x86 volumio[1172]: The disk contains an unclean file system (0, 0). Apr 28 22:18:51 volumio-x86 volumio[1172]: The file system wasn't safely closed on Windows. Fixing. Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:51 volumio-x86 volumio[1172]: Upnp client error: Error: This socket has been ended by the other party Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:51 volumio-x86 volumio[1172]: info: Apr 28 22:18:51 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:18:51 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 6 milliseconds Apr 28 22:18:51 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:18:51 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:18:51 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:18:51 volumio-x86 volumio[1172]: info: Scanning new location : "USB/系统" Apr 28 22:18:51 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:18:51 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":158886,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1018 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:18:51 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:18:51 volumio-x86 volumio[1172]: info: ------------------------------ 47ms Apr 28 22:18:51 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:51 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:18:51 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:51 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:51 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 2 milliseconds Apr 28 22:18:52 volumio-x86 volumio[1172]: info: MyVolumio login type: Token Apr 28 22:18:53 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:53 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:53 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 1 milliseconds Apr 28 22:18:54 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:18:56 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:56 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:56 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 1 milliseconds Apr 28 22:18:57 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:18:57 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:18:57 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 1 milliseconds Apr 28 22:18:58 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:19:01 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 22:19:01 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 22:19:01 volumio-x86 volumio[1172]: info: sendMpdCommand rescan took 2 milliseconds Apr 28 22:19:02 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 22:19:05 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioGetState Apr 28 22:19:05 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:08 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 22:19:08 volumio-x86 volumio[1172]: info: CURURI: music-library Apr 28 22:19:08 volumio-x86 volumio[1172]: info: Preload queue cleared Apr 28 22:19:16 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:16 volumio-x86 volumio[1172]: info: Apr 28 22:19:16 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:16 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:16 volumio-x86 volumio[1172]: info: Apr 28 22:19:16 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:16 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:16 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:16 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:16 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:16 volumio-x86 volumio[1172]: info: sendMpdCommand status took 31 milliseconds Apr 28 22:19:16 volumio-x86 volumio[1172]: info: sendMpdCommand status took 31 milliseconds Apr 28 22:19:16 volumio-x86 volumio[1172]: info: sendMpdCommand status took 31 milliseconds Apr 28 22:19:16 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 22:19:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184704,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184704,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ------------------------------ 73ms Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ------------------------------ 74ms Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Apr 28 22:19:17 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 2 milliseconds Apr 28 22:19:17 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185068,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"901 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:17 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:17 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:17 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:17 volumio-x86 volumio[1172]: info: ------------------------------ 36ms Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:17 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Apr 28 22:19:18 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Apr 28 22:19:18 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 23 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 23 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 23 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185910,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"941 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":185910,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"941 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ------------------------------ 93ms Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ------------------------------ 94ms Apr 28 22:19:18 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:18 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:18 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:18 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Apr 28 22:19:18 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Apr 28 22:19:18 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 18 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 18 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand status took 18 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186730,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1007 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":186730,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1007 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:18 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:18 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:18 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ------------------------------ 77ms Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ------------------------------ 78ms Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:19 volumio-x86 volumio[1172]: info: Apr 28 22:19:19 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:19 volumio-x86 volumio[1172]: info: Apr 28 22:19:19 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:19 volumio-x86 volumio[1172]: info: sendMpdCommand status took 23 milliseconds Apr 28 22:19:19 volumio-x86 volumio[1172]: info: sendMpdCommand status took 22 milliseconds Apr 28 22:19:19 volumio-x86 volumio[1172]: info: sendMpdCommand status took 22 milliseconds Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:19 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 28 22:19:19 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187572,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"914 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:19 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":187572,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"914 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:19 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:19 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:19 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ------------------------------ 90ms Apr 28 22:19:19 volumio-x86 volumio[1172]: info: ------------------------------ 90ms Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:19 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:20 volumio-x86 volumio[1172]: info: Apr 28 22:19:20 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:20 volumio-x86 volumio[1172]: info: Apr 28 22:19:20 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:20 volumio-x86 volumio[1172]: info: sendMpdCommand status took 14 milliseconds Apr 28 22:19:20 volumio-x86 volumio[1172]: info: sendMpdCommand status took 13 milliseconds Apr 28 22:19:20 volumio-x86 volumio[1172]: info: sendMpdCommand status took 13 milliseconds Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatetrue Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:20 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":188415,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"890 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:20 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":188415,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"890 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:20 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:20 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:20 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ------------------------------ 65ms Apr 28 22:19:20 volumio-x86 volumio[1172]: info: ------------------------------ 66ms Apr 28 22:19:20 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:20 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:20 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:20 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: MPD Database updated - AlbumList cache refreshed Apr 28 22:19:21 volumio-x86 volumio[1172]: info: Apr 28 22:19:21 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: database Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:21 volumio-x86 volumio[1172]: info: Apr 28 22:19:21 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: update Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:21 volumio-x86 volumio[1172]: info: sendMpdCommand status took 15 milliseconds Apr 28 22:19:21 volumio-x86 volumio[1172]: info: sendMpdCommand status took 15 milliseconds Apr 28 22:19:21 volumio-x86 volumio[1172]: info: sendMpdCommand status took 14 milliseconds Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: Command Router : Notfying DB Updatefalse Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 28 22:19:21 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 28 22:19:21 volumio-x86 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189257,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"974 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:21 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":189257,"duration":220,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"974 Kbps","isStreaming":false,"title":"谁","artist":"洋澜一","album":"谁","uri":"USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RIVQTZR.flac","trackType":"flac"} Apr 28 22:19:21 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService play Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:21 volumio-x86 volumio[1172]: info: Received an update from plugin. extracting info from payload Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:21 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ------------------------------ 48ms Apr 28 22:19:21 volumio-x86 volumio[1172]: info: ------------------------------ 48ms Apr 28 22:19:21 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:21 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:22 volumio-x86 volumio[1172]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 28 22:19:22 volumio-x86 volumio[1172]: info: Retrying Login Due to Network Error Apr 28 22:19:23 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 22:19:23 volumio-x86 volumio[1172]: info: CURURI: music-library/USB Apr 28 22:19:23 volumio-x86 volumio[1172]: info: Preload queue cleared Apr 28 22:19:24 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 22:19:24 volumio-x86 volumio[1172]: info: CURURI: music-library/USB/系统 Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preload queue cleared Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/陈晓竹-丁香花 (天籁版).flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/青鸟飞鱼-此生不换.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/洋澜一-天际.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/马健涛-搀扶.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/陈晓竹 - 春庭雪 (空灵版).flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/陈晓竹 - 秋殇.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/洋澜一-谁.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Preloading song: music-library/USB/系统/陈晓竹 - 走江南.flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/陈晓竹-丁香花 (天籁版).flac in service mpd Apr 28 22:19:24 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%99%88%E6%99%93%E7%AB%B9/%E4%B8%81%E9%A6%99%E8%8A%B1%20(%E5%A4%A9%E7%B1%81%E7%89%88)/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E6%99%93%E7%AB%B9-%E4%B8%81%E9%A6%99%E8%8A%B1%20(%E5%A4%A9%E7%B1%81%E7%89%88).flac&metadata=false Apr 28 22:19:24 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/陈晓竹-丁香花 (天籁版).flac Apr 28 22:19:24 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/青鸟飞鱼-此生不换.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/洋澜一-天际.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/马健涛-搀扶.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/陈晓竹 - 春庭雪 (空灵版).flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/陈晓竹 - 秋殇.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/洋澜一-谁.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: Exploding uri music-library/USB/系统/陈晓竹 - 走江南.flac in service mpd Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%9D%92%E9%B8%9F%E9%A3%9E%E9%B1%BC/%E4%BB%99%E5%89%91%E5%A5%87%E4%BE%A0%E4%BC%A0%E4%B8%89%20%E7%94%B5%E8%A7%86%E5%8E%9F%E5%A3%B0%E5%B8%A6/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%9D%92%E9%B8%9F%E9%A3%9E%E9%B1%BC-%E6%AD%A4%E7%94%9F%E4%B8%8D%E6%8D%A2.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/青鸟飞鱼-此生不换.flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E6%B4%8B%E6%BE%9C%E4%B8%80/%E5%A4%A9%E9%99%85/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E6%B4%8B%E6%BE%9C%E4%B8%80-%E5%A4%A9%E9%99%85.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/洋澜一-天际.flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%A9%AC%E5%81%A5%E6%B6%9B/%E6%90%80%E6%89%B6/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%A9%AC%E5%81%A5%E6%B6%9B-%E6%90%80%E6%89%B6.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/马健涛-搀扶.flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%99%88%E6%99%93%E7%AB%B9/%E6%98%A5%E5%BA%AD%E9%9B%AA%20(%E7%A9%BA%E7%81%B5%E7%89%88)/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E6%99%93%E7%AB%B9%20-%20%E6%98%A5%E5%BA%AD%E9%9B%AA%20(%E7%A9%BA%E7%81%B5%E7%89%88).flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/陈晓竹 - 春庭雪 (空灵版).flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%99%88%E6%99%93%E7%AB%B9/%E7%A7%8B%E6%AE%87/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E6%99%93%E7%AB%B9%20-%20%E7%A7%8B%E6%AE%87.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/陈晓竹 - 秋殇.flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E6%B4%8B%E6%BE%9C%E4%B8%80/%E8%B0%81/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E6%B4%8B%E6%BE%9C%E4%B8%80-%E8%B0%81.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/洋澜一-谁.flac Apr 28 22:19:25 volumio-x86 volumio[1172]: info: ALBUMART /albumart?cacheid=830&web=%E9%99%88%E6%99%93%E7%AB%B9/%E8%B5%B0%E6%B1%9F%E5%8D%97/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E6%99%93%E7%AB%B9%20-%20%E8%B5%B0%E6%B1%9F%E5%8D%97.flac&metadata=false Apr 28 22:19:25 volumio-x86 volumio[1172]: info: URI /mnt/USB/系统/陈晓竹 - 走江南.flac Apr 28 22:19:32 volumio-x86 volumio[1172]: info: MyVolumio login type: Token Apr 28 22:19:47 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 13 Apr 28 22:19:47 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 14 Apr 28 22:19:47 volumio-x86 volumio[1172]: info: Prefetching next song Apr 28 22:19:47 volumio-x86 volumio[1172]: info: DOING PREFETCH IN MPD Apr 28 22:19:47 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/$RECYCLE.BIN/S-1-5-21-467624554-363743083-2576460722-500/$RXBJWR2.flac" Apr 28 22:19:51 volumio-x86 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Apr 28 22:19:51 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 14 Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 14 Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:52 volumio-x86 volumio[1172]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 28 22:19:52 volumio-x86 volumio[1172]: info: Apr 28 22:19:52 volumio-x86 volumio[1172]: ---------------------------- MPD announces state update: player Apr 28 22:19:52 volumio-x86 volumio[1172]: info: ControllerMpd::getState Apr 28 22:19:52 volumio-x86 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Apr 28 22:19:52 volumio-x86 volumio[1172]: info: sendMpdCommand status took 1 milliseconds Apr 28 22:19:52 volumio-x86 volumio[1172]: verbose: ControllerMpd::parseState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: ControllerMpd::pushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::servicePushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreStateMachine::pushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 14 Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CorePlayQueue::getTrack 14 Apr 28 22:19:52 volumio-x86 volumio[1172]: 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} Apr 28 22:19:52 volumio-x86 volumio[1172]: verbose: CURRENT POSITION 14 Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState stateService stop Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreCommandRouter::volumioPushState Apr 28 22:19:52 volumio-x86 volumio[1172]: info: CoreStateMachine::stPlaybackTimer Apr 28 22:19:52 volumio-x86 volumio[1172]: info: ------------------------------ 8ms Apr 28 22:19:52 volumio-x86 volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 22:19:52 volumio-x86 volumio[1172]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Apr 28 22:19:52 volumio-x86 volumio[1172]: at Object.readdirSync (fs.js:1048:3) Apr 28 22:19:52 volumio-x86 volumio[1172]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Apr 28 22:19:52 volumio-x86 volumio[1172]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Apr 28 22:19:52 volumio-x86 volumio[1172]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Apr 28 22:19:52 volumio-x86 volumio[1172]: errno: -20, Apr 28 22:19:52 volumio-x86 volumio[1172]: syscall: 'scandir', Apr 28 22:19:52 volumio-x86 volumio[1172]: code: 'ENOTDIR', Apr 28 22:19:52 volumio-x86 volumio[1172]: path: '/tmp/peppyrunning' Apr 28 22:19:52 volumio-x86 volumio[1172]: } Apr 28 22:19:52 volumio-x86 volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 22:19:52 volumio-x86 sudo[3555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 22:18 Apr 28 22:19:52 volumio-x86 sudo[3555]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"