Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:00 volumio kernel: usb 1-1.3: USB disconnect, device number 3 Dec 06 19:50:00 volumio volumio[7840]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/pci-0000:00:12.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/b73ea221-01 /dev/disk/by-id/usb-SanDisk_Cruzer_Blade_4C530000130604214103-0:0-part1 /dev/disk/by-uuid/88D9-075E","DEVNAME":"/dev/sda1","DEVPATH":"/devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"1","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"88D9-075E","ID_FS_UUID_ENC":"88D9-075E","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Blade","ID_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5567","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30029824","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"b73ea221-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"b73ea221","ID_PATH":"pci-0000:00:12.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_12_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Cruzer_Blade_4C530000130604214103-0:0","ID_SERIAL_SHORT":"4C530000130604214103","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":"Cruzer_Blade","ID_USB_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5567","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"SanDisk_Cruzer_Blade_4C530000130604214103-0:0","ID_USB_SERIAL_SHORT":"4C530000130604214103","ID_USB_TYPE":"disk","ID_USB_VENDOR":"SanDisk","ID_USB_VENDOR_ENC":"SanDisk\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3337","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2590124"} Dec 06 19:50:00 volumio sudo[11960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 06 19:50:00 volumio sudo[11960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:50:00 volumio sudo[11960]: pam_unix(sudo:session): session closed for user root Dec 06 19:50:00 volumio volumio[7840]: umount: /media/88D9-075E: target is busy. Dec 06 19:50:00 volumio volumio[7840]: error: Failed to umount 88D9-075E: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Dec 06 19:50:00 volumio volumio[7840]: umount: /media/88D9-075E: target is busy. Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:01 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9491) Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:05 volumio volumio[7840]: info: Scanning removed location : "USB/88D9-075E" Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: info: Dec 06 19:50:05 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:05 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: info: Dec 06 19:50:05 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:05 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: info: Dec 06 19:50:05 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:05 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:05 volumio volumio[7840]: info: sendMpdCommand status took 3 milliseconds Dec 06 19:50:05 volumio volumio[7840]: info: sendMpdCommand status took 2 milliseconds Dec 06 19:50:05 volumio volumio[7840]: info: sendMpdCommand status took 1 milliseconds Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:05 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:05 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:06 volumio volumio[7840]: info: MPD Database updated - AlbumList cache refreshed Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: database Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: MPD Database updated - AlbumList cache refreshed Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: database Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: MPD Database updated - AlbumList cache refreshed Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: database Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: Dec 06 19:50:06 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 41 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 42 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 41 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 8 milliseconds Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: info: Command Router : Notfying DB Updatefalse Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208591,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 79ms Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 50 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 49 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 48 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 47 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 47 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 46 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 44 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand status took 43 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: info: Command Router : Notfying DB Updatefalse Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: info: Command Router : Notfying DB Updatefalse Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208591,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208591,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 138ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 138ms Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 06 19:50:06 volumio volumio[7840]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: verbose: ControllerMpd::parseTrackInfo Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208727,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"381 Kbps","isStreaming":false,"title":"哭砂","artist":"王闻","album":"人生如歌Ⅱ","uri":"USB/88D9-075E/哭砂-王闻-40827973-2000.flac","trackType":"flac"} Dec 06 19:50:06 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState stateService play Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:06 volumio volumio[7840]: info: Received an update from plugin. extracting info from payload Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:06 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:06 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 153ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 154ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 152ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 153ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 151ms Dec 06 19:50:06 volumio volumio[7840]: info: ------------------------------ 152ms Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces state update: player Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces state update: player Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces state update: player Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 1ms Dec 06 19:50:15 volumio volumio[7840]: info: sendMpdCommand status took 1 milliseconds Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 0ms Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 9 Dec 06 19:50:15 volumio volumio[7840]: 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} Dec 06 19:50:15 volumio volumio[7840]: verbose: CURRENT POSITION 9 Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus play Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::play index undefined Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::startPlaybackTimer Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::clearAddPlayTracks USB/88D9-075E/从开始到现在-张信哲-1025738-2000.flac Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand stop Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 9ms Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: info: sendMpdCommand status took 12 milliseconds Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 12ms Dec 06 19:50:15 volumio volumio[7840]: info: sendMpdCommand status took 12 milliseconds Dec 06 19:50:15 volumio volumio[7840]: info: sendMpdCommand stop took 4 milliseconds Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand clear Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: 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} Dec 06 19:50:15 volumio volumio[7840]: verbose: CURRENT POSITION 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: No code Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: 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} Dec 06 19:50:15 volumio volumio[7840]: verbose: CURRENT POSITION 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: No code Dec 06 19:50:15 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:15 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:15 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 28ms Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 29ms Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: info: sendMpdCommand clear took 33 milliseconds Dec 06 19:50:15 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand add "USB/88D9-075E/从开始到现在-张信哲-1025738-2000.flac" Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: info: Dec 06 19:50:15 volumio volumio[7840]: ---------------------------- MPD announces system playlist update Dec 06 19:50:15 volumio volumio[7840]: info: Ignoring MPD Status Update Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: error: updateQueue error: null Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 4ms Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 3ms Dec 06 19:50:15 volumio volumio[7840]: info: ------------------------------ 3ms Dec 06 19:50:15 volumio kernel: fat__get_entry: 710 callbacks suppressed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 06 19:50:15 volumio kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 06 19:50:15 volumio volumio[7840]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E4%BF%A1%E5%93%B2/%E4%BB%8E%E5%BC%80%E5%A7%8B%E5%88%B0%E7%8E%B0%E5%9C%A8/3ab0fd62-9222-4348-9fa1-0ff5b197e17e.jpg' Dec 06 19:50:15 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using ehci-pci Dec 06 19:50:15 volumio kernel: usb 1-1.3: New USB device found, idVendor=14cd, idProduct=6116, bcdDevice= 2.20 Dec 06 19:50:15 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 06 19:50:15 volumio kernel: usb 1-1.3: Product: USB Mass Storage Device Dec 06 19:50:15 volumio kernel: usb 1-1.3: Manufacturer: Generic Dec 06 19:50:15 volumio kernel: usb 1-1.3: SerialNumber: 116AC2101219 Dec 06 19:50:15 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Dec 06 19:50:15 volumio kernel: scsi host3: usb-storage 1-1.3:1.0 Dec 06 19:50:16 volumio kernel: scsi 3:0:0:0: Direct-Access Mass Storage Device PQ: 0 ANSI: 0 Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: Attached scsi generic sg0 type 0 Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] 488397166 512-byte logical blocks: (250 GB/233 GiB) Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] Write Protect is off Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] Mode Sense: 03 00 00 00 Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] No Caching mode page found Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] Assuming drive cache: write through Dec 06 19:50:16 volumio kernel: sdc: sdc1 Dec 06 19:50:16 volumio kernel: sd 3:0:0:0: [sdc] Attached SCSI disk Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:16 volumio volumio[7840]: info: Mounting Device 系统 Dec 06 19:50:16 volumio sudo[12090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/系统 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 06 19:50:16 volumio sudo[12090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:50:17 volumio ntfs-3g[12093]: Version 2022.10.3 integrated FUSE 28 Dec 06 19:50:17 volumio ntfs-3g[12093]: Mounted /dev/sdc1 (Read-Only, label "系统", NTFS 3.1) Dec 06 19:50:17 volumio ntfs-3g[12093]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 06 19:50:17 volumio ntfs-3g[12093]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,ro,default_permissions,fsname=/dev/sdc1,blkdev,blksize=4096 Dec 06 19:50:17 volumio ntfs-3g[12093]: Global ownership and permissions enforced, configuration type 7 Dec 06 19:50:17 volumio sudo[12090]: pam_unix(sudo:session): session closed for user root Dec 06 19:50:17 volumio volumio[7840]: The disk contains an unclean file system (0, 0). Dec 06 19:50:17 volumio volumio[7840]: Metadata kept in Windows cache, refused to mount. Dec 06 19:50:17 volumio volumio[7840]: Falling back to read-only mount because the NTFS partition is in an Dec 06 19:50:17 volumio volumio[7840]: unsafe state. Please resume and shutdown Windows fully (no hibernation Dec 06 19:50:17 volumio volumio[7840]: or fast restarting.) Dec 06 19:50:17 volumio volumio[7840]: Could not mount read-write, trying read-only Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:17 volumio volumio[7840]: info: Scanning new location : "USB/系统" Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: info: Dec 06 19:50:17 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: info: Dec 06 19:50:17 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: info: Dec 06 19:50:17 volumio volumio[7840]: ---------------------------- MPD announces state update: update Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::getState Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 6 milliseconds Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 5 milliseconds Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 6 milliseconds Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 3 milliseconds Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: 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} Dec 06 19:50:17 volumio volumio[7840]: verbose: CURRENT POSITION 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: No code Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: 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} Dec 06 19:50:17 volumio volumio[7840]: verbose: CURRENT POSITION 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: No code Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: ------------------------------ 60ms Dec 06 19:50:17 volumio volumio[7840]: info: ------------------------------ 59ms Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 57 milliseconds Dec 06 19:50:17 volumio volumio[7840]: info: sendMpdCommand status took 55 milliseconds Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: info: Command Router : Notfying DB Updatetrue Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:50:17 volumio volumio[7840]: verbose: ControllerMpd::parseState Dec 06 19:50:17 volumio volumio[7840]: info: ControllerMpd::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::servicePushState Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: 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} Dec 06 19:50:17 volumio volumio[7840]: verbose: CURRENT POSITION 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState stateService stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: No code Dec 06 19:50:17 volumio volumio[7840]: info: CoreStateMachine::pushState Dec 06 19:50:17 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:17 volumio volumio[7840]: info: CoreCommandRouter::volumioPushState Dec 06 19:50:17 volumio volumio[7840]: info: ------------------------------ 80ms Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio[7840]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 06 19:50:24 volumio volumio[7840]: info: CoreCommandRouter::volumioGetState Dec 06 19:50:24 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:24 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 06 19:50:24 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:25 volumio volumio[7840]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 5 Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::volumioGetState Dec 06 19:50:25 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 19:50:25 volumio volumio[7840]: info: Received Get System Info Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 19:50:25 volumio volumio[7840]: info: Discovery: Getting this device information Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::volumioGetState Dec 06 19:50:25 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::volumioGetState Dec 06 19:50:25 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:25 volumio volumio[7840]: info: Listing playlists Dec 06 19:50:25 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:27 volumio volumio[7840]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E4%BF%A1%E5%93%B2/%E4%BB%8E%E5%BC%80%E5%A7%8B%E5%88%B0%E7%8E%B0%E5%9C%A8/3ab0fd62-9222-4348-9fa1-0ff5b197e17e.jpg' Dec 06 19:50:27 volumio kernel: fat__get_entry: 1334 callbacks suppressed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 06 19:50:27 volumio kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:30 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:33 volumio volumio[7840]: info: CoreCommandRouter::volumioGetQueue Dec 06 19:50:33 volumio volumio[7840]: info: CoreStateMachine::getQueue Dec 06 19:50:33 volumio volumio[7840]: info: CorePlayQueue::getQueue Dec 06 19:50:34 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 19:50:34 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 19:50:34 volumio volumio[7840]: info: Discovery: Getting this device information Dec 06 19:50:34 volumio volumio[7840]: info: CoreCommandRouter::volumioGetState Dec 06 19:50:34 volumio volumio[7840]: info: CorePlayQueue::getTrack 10 Dec 06 19:50:34 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:35 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:36 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:37 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 06 19:50:38 volumio volumio[7840]: info: CURURI: music-library Dec 06 19:50:38 volumio volumio[7840]: info: Preload queue cleared Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:38 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:39 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 06 19:50:39 volumio volumio[7840]: info: CURURI: music-library/USB Dec 06 19:50:39 volumio volumio[7840]: info: Preload queue cleared Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:40 volumio volumio[7840]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 06 19:50:40 volumio volumio[7840]: info: CURURI: music-library/USB/系统 Dec 06 19:50:40 volumio volumio[7840]: info: Preload queue cleared Dec 06 19:50:40 volumio volumio[7840]: info: Preloading song: music-library/USB/系统/陈瑞-情罪.flac Dec 06 19:50:40 volumio volumio[7840]: info: Preloading song: music-library/USB/系统/周传雄-青花.flac Dec 06 19:50:40 volumio volumio[7840]: info: Preloading song: music-library/USB/系统/刀郎-喀什噶尔胡杨.flac Dec 06 19:50:40 volumio volumio[7840]: info: Preloading song: music-library/USB/系统/刀郎-西海情歌.flac Dec 06 19:50:40 volumio volumio[7840]: info: Preloading song: music-library/USB/系统/群星.-.[阿尔兹记忆的爱情](2019)[WAV].wav Dec 06 19:50:40 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/陈瑞-情罪.flac in service mpd Dec 06 19:50:40 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%E9%99%88%E7%91%9E/%E6%83%85%E7%BD%AA/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E7%91%9E-%E6%83%85%E7%BD%AA.flac&metadata=false Dec 06 19:50:40 volumio volumio[7840]: info: URI /mnt/USB/系统/陈瑞-情罪.flac Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:41 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/周传雄-青花.flac in service mpd Dec 06 19:50:41 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%E5%91%A8%E4%BC%A0%E9%9B%84/%E8%93%9D%E8%89%B2%E5%9C%9F%E8%80%B3%E5%85%B6/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%91%A8%E4%BC%A0%E9%9B%84-%E9%9D%92%E8%8A%B1.flac&metadata=false Dec 06 19:50:41 volumio volumio[7840]: info: URI /mnt/USB/系统/周传雄-青花.flac Dec 06 19:50:41 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/刀郎-喀什噶尔胡杨.flac in service mpd Dec 06 19:50:41 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%E5%88%80%E9%83%8E/%E5%96%80%E4%BB%80%E5%99%B6%E5%B0%94%E8%83%A1%E6%9D%A8/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%88%80%E9%83%8E-%E5%96%80%E4%BB%80%E5%99%B6%E5%B0%94%E8%83%A1%E6%9D%A8.flac&metadata=false Dec 06 19:50:41 volumio volumio[7840]: info: URI /mnt/USB/系统/刀郎-喀什噶尔胡杨.flac Dec 06 19:50:41 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/刀郎-西海情歌.flac in service mpd Dec 06 19:50:41 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%E5%88%80%E9%83%8E/%E5%88%80%E9%83%8E%E2%85%A2/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E5%88%80%E9%83%8E-%E8%A5%BF%E6%B5%B7%E6%83%85%E6%AD%8C.flac&metadata=false Dec 06 19:50:41 volumio volumio[7840]: info: URI /mnt/USB/系统/刀郎-西海情歌.flac Dec 06 19:50:41 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/群星.-.[阿尔兹记忆的爱情](2019)[WAV].wav in service mpd Dec 06 19:50:41 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=//extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E7%BE%A4%E6%98%9F.-.%5B%E9%98%BF%E5%B0%94%E5%85%B9%E8%AE%B0%E5%BF%86%E7%9A%84%E7%88%B1%E6%83%85%5D(2019)%5BWAV%5D.wav&metadata=false Dec 06 19:50:41 volumio volumio[7840]: info: URI /mnt/USB/系统/群星.-.[阿尔兹记忆的爱情](2019)[WAV].wav Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:42 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:43 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:44 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:45 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:46 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:47 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:48 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:49 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:50 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:52 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:53 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:54 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:55 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:56 volumio volumio[7840]: info: Preload queue cleared Dec 06 19:50:56 volumio volumio[7840]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 06 19:50:56 volumio volumio[7840]: info: CoreStateMachine::ClearQueue Dec 06 19:50:56 volumio volumio[7840]: info: CoreStateMachine::stop Dec 06 19:50:56 volumio volumio[7840]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 19:50:56 volumio volumio[7840]: info: CorePlayQueue::clearPlayQueue Dec 06 19:50:56 volumio volumio[7840]: info: CorePlayQueue::saveQueue Dec 06 19:50:56 volumio volumio[7840]: info: CoreCommandRouter::volumioPushQueue Dec 06 19:50:56 volumio volumio[7840]: info: CoreStateMachine::addQueueItems Dec 06 19:50:56 volumio volumio[7840]: info: CorePlayQueue::addQueueItems Dec 06 19:50:56 volumio volumio[7840]: info: Preload queue cleared Dec 06 19:50:56 volumio volumio[7840]: info: Adding Item to queue: music-library/USB/系统/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE] Dec 06 19:50:56 volumio volumio[7840]: info: Exploding uri music-library/USB/系统/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE] in service mpd Dec 06 19:50:56 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%3F%3F%3F%3F/%3F%3F%3F%3FHQII%3F%3FMQA%CD%B7%3F%3F%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%C5%A3%3F/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E5%AE%81%E3%80%8A%E7%A0%B4%E6%99%93HQ%E2%85%A1%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E9%99%88%E5%AE%81%E3%80%8A%E7%A0%B4%E6%99%93HQ%E2%85%A1%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E9%99%88%E5%AE%81%20-%20%E7%A0%B4%E6%99%93HQII%EF%BC%88MQA%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%EF%BC%89.cue&metadata=false Dec 06 19:50:56 volumio volumio[7840]: info: URI /mnt/USB/系统/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE]/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE]/陈宁 - 破晓HQII（MQA头版限量编号）.cue Dec 06 19:50:56 volumio volumio[7840]: info: ALBUMART /albumart?cacheid=523&web=%3F%3F%3F%3F/%3F%3F%3F%3FHQII%3F%3FMQA%CD%B7%3F%3F%3F%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%C5%A3%3F/extralarge&path=%2Fmnt%2FUSB%2F%E7%B3%BB%E7%BB%9F%2F%E9%99%88%E5%AE%81%E3%80%8A%E7%A0%B4%E6%99%93HQ%E2%85%A1%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E9%99%88%E5%AE%81%E3%80%8A%E7%A0%B4%E6%99%93HQ%E2%85%A1%E3%80%8B%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%5B%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E9%99%88%E5%AE%81%20-%20%E7%A0%B4%E6%99%93HQII%EF%BC%88MQA%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%EF%BC%89.cue&metadata=false Dec 06 19:50:56 volumio volumio[7840]: info: URI /mnt/USB/系统/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE]/陈宁《破晓HQⅡ》头版限量编号[低速原抓WAV+CUE]/陈宁 - 破晓HQII（MQA头版限量编号）.cue Dec 06 19:50:56 volumio volumio[7840]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 19:50:56 volumio volumio[7840]: Error: Unable to resolve or reject the same promise twice Dec 06 19:50:56 volumio volumio[7840]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 06 19:50:56 volumio volumio[7840]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 06 19:50:56 volumio volumio[7840]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 06 19:50:56 volumio volumio[7840]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 06 19:50:56 volumio volumio[7840]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 06 19:50:56 volumio volumio[7840]: at Socket.emit (node:events:514:28) Dec 06 19:50:56 volumio volumio[7840]: at addChunk (node:internal/streams/readable:343:12) Dec 06 19:50:56 volumio volumio[7840]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 06 19:50:56 volumio volumio[7840]: at Readable.push (node:internal/streams/readable:253:10) Dec 06 19:50:56 volumio volumio[7840]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 06 19:50:56 volumio volumio[7840]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 19:50:56 volumio sudo[12238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 19:49' Dec 06 19:50:56 volumio sudo[12238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:50:57 volumio sudo[12238]: pam_unix(sudo:session): session closed for user root Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:57 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 06 19:50:58 volumio ntfs-3g[12093]: Unmounting /dev/sdc1 (系统) Dec 06 19:50:58 volumio systemd[1]: media-\xe7\xb3\xbb\xe7\xbb\x9f.mount: Deactivated successfully. Dec 06 19:50:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 06 19:50:58 volumio systemd[1]: volumio.service: Consumed 13min 36.195s CPU time. Dec 06 19:50:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 06 19:50:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 06 19:50:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Dec 06 19:50:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 06 19:50:58 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 06 19:50:58 volumio systemd[1]: volumio.service: Consumed 13min 36.195s CPU time. Dec 06 19:50:58 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 06 19:50:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 06 19:50:59 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:50:59 volumio volumio[12277]: info: ----- Volumio3 ---- Dec 06 19:50:59 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:50:59 volumio volumio[12277]: info: ----- System startup ---- Dec 06 19:50:59 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:50:59 volumio volumio[12277]: info: MYVOLUMIO Environment detected Dec 06 19:50:59 volumio volumio[12277]: info: Plugin folders cleanup Dec 06 19:50:59 volumio volumio[12277]: info: Scanning into folder /volumio/app/plugins/ Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category audio_interface Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category miscellanea Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category music_service Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category plugins.json Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category system_controller Dec 06 19:50:59 volumio volumio[12277]: info: Scanning category user_interface Dec 06 19:50:59 volumio volumio[12277]: info: Scanning into folder /data/plugins/ Dec 06 19:50:59 volumio volumio[12277]: info: Plugin folders cleanup completed Dec 06 19:50:59 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:50:59 volumio volumio[12277]: info: ----- Core plugins startup ---- Dec 06 19:50:59 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugins from folder /volumio/app/plugins/ Dec 06 19:50:59 volumio volumio[12277]: info: Adding plugin upnp to MyMusic Plugins Dec 06 19:50:59 volumio volumio[12277]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 06 19:50:59 volumio volumio[12277]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugins from folder /data/plugins/ Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "system"... Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "appearance"... Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "network"... Dec 06 19:50:59 volumio volumio[12277]: info: Refreshing Cached IP Addresses Dec 06 19:50:59 volumio sudo[12311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "services"... Dec 06 19:50:59 volumio sudo[12311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "alsa_controller"... Dec 06 19:50:59 volumio sudo[12313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 19:50:59 volumio sudo[12313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:50:59 volumio sudo[12311]: pam_unix(sudo:session): session closed for user root Dec 06 19:50:59 volumio sudo[12313]: pam_unix(sudo:session): session closed for user root Dec 06 19:50:59 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "wizard"... Dec 06 19:50:59 volumio volumio[12277]: info: Loading plugin "networkfs"... Dec 06 19:50:59 volumio volumio[12277]: info: Starting Udev Watcher for removable devices Dec 06 19:50:59 volumio volumio[12277]: info: Ignoring mount for partition: boot Dec 06 19:50:59 volumio volumio[12277]: info: Ignoring mount for partition: volumio Dec 06 19:50:59 volumio volumio[12277]: info: Ignoring mount for partition: volumio_data Dec 06 19:50:59 volumio volumio[12277]: info: Mounting Device 系统 Dec 06 19:50:59 volumio sudo[12347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/系统 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 06 19:50:59 volumio sudo[12347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:00 volumio ntfs-3g[12350]: Version 2022.10.3 integrated FUSE 28 Dec 06 19:51:00 volumio sudo[12347]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:00 volumio ntfs-3g[12350]: Mounted /dev/sdc1 (Read-Only, label "系统", NTFS 3.1) Dec 06 19:51:00 volumio ntfs-3g[12350]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 06 19:51:00 volumio ntfs-3g[12350]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,ro,default_permissions,fsname=/dev/sdc1,blkdev,blksize=4096 Dec 06 19:51:00 volumio ntfs-3g[12350]: Global ownership and permissions enforced, configuration type 7 Dec 06 19:51:00 volumio volumio[12277]: The disk contains an unclean file system (0, 0). Dec 06 19:51:00 volumio volumio[12277]: Metadata kept in Windows cache, refused to mount. Dec 06 19:51:00 volumio volumio[12277]: Falling back to read-only mount because the NTFS partition is in an Dec 06 19:51:00 volumio volumio[12277]: unsafe state. Please resume and shutdown Windows fully (no hibernation Dec 06 19:51:00 volumio volumio[12277]: or fast restarting.) Dec 06 19:51:00 volumio volumio[12277]: Could not mount read-write, trying read-only Dec 06 19:51:00 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 19:51:00 volumio volumio[12277]: info: Loading plugin "volumio_command_line_client"... Dec 06 19:51:00 volumio volumio[12277]: info: Loading plugin "upnp"... Dec 06 19:51:00 volumio volumio[12277]: info: [1765021860369] Starting Upmpd Daemon Dec 06 19:51:00 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 19:51:00 volumio volumio[12277]: info: Loading plugin "my_music"... Dec 06 19:51:00 volumio volumio[12277]: info: Loading plugin "mpd"... Dec 06 19:51:00 volumio volumio[12277]: info: Loading plugin "upnp_browser"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "alarm-clock"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "airplay_emulation"... Dec 06 19:51:01 volumio volumio[12277]: info: Starting Shairport Sync Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "last_100"... Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "webradio"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "i2s_dacs"... Dec 06 19:51:01 volumio volumio[12277]: info: I2S DAC not set, start Auto-detection Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "volumiodiscovery"... Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 19:51:01 volumio node[12277]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 19:51:01 volumio node[12277]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** For more information see Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 19:51:01 volumio volumio[12277]: *** WARNING *** For more information see Dec 06 19:51:01 volumio node[12277]: *** WARNING *** For more information see Dec 06 19:51:01 volumio node[12277]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 19:51:01 volumio node[12277]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 19:51:01 volumio node[12277]: *** WARNING *** For more information see Dec 06 19:51:01 volumio volumio[12277]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 06 19:51:01 volumio volumio[12277]: info: Discovery: Started advertising with name: Volumio Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "outputs"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "albumart"... Dec 06 19:51:01 volumio volumio[12277]: info: Plugin example_plugin is not enabled Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "inputs"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "updater_comm"... Dec 06 19:51:01 volumio volumio[12277]: info: Plugin mpdemulation is not enabled Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "rest_api"... Dec 06 19:51:01 volumio volumio[12277]: info: Loading plugin "websocket"... Dec 06 19:51:01 volumio volumio[12277]: info: Starting Socket.io Server version 1.7.4 Dec 06 19:51:01 volumio volumio[12277]: info: Loading i18n strings for locale zh Dec 06 19:51:01 volumio volumio[12355]: Forking 3 albumart workers Dec 06 19:51:01 volumio volumio[12277]: Updating browse sources language Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::initPlayerControls Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: Express server listening on port 3000 Dec 06 19:51:01 volumio volumio[12277]: [Metrics] WebUI: 2s 522.39ms Dec 06 19:51:01 volumio volumio[12277]: info: Setting Device type: x86 Dec 06 19:51:01 volumio volumio[12277]: info: CoreStateMachine::resetVolumioState Dec 06 19:51:01 volumio volumio[12277]: info: CoreStateMachine::getcurrentVolume Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 06 19:51:01 volumio volumio[12277]: info: Completed loading Core Plugins Dec 06 19:51:01 volumio volumio[12277]: info: Preparing to generate the ALSA configuration file Dec 06 19:51:01 volumio volumio[12277]: info: Volumio Network Manager: Network status updated: 1 Dec 06 19:51:01 volumio volumio[12277]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 06 19:51:01 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Dec 06 19:51:01 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Dec 06 19:51:01 volumio volumio[12277]: info: Reloading queue from file Dec 06 19:51:01 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Dec 06 19:51:01 volumio volumio[12277]: info: VolumeController:: Volume=46 Mute =false Dec 06 19:51:01 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:01 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:01 volumio volumio[12277]: info: CoreStateMachine::updateTrackBlock Dec 06 19:51:01 volumio volumio[12277]: info: CorePlayQueue::getTrackBlock Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 19:51:01 volumio volumio[12277]: info: Asound.conf file unchanged, so no further update is needed Dec 06 19:51:01 volumio volumio[12277]: info: Output device has changed, restarting MPD Dec 06 19:51:01 volumio sudo[12404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 19:51:01 volumio volumio[12277]: info: Output device has changed, restarting Shairport Sync Dec 06 19:51:01 volumio sudo[12404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio sudo[12404]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:01 volumio sudo[12406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 19:51:01 volumio sudo[12406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:01 volumio volumio[12277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 19:51:01 volumio volumio[12277]: info: ___________ START PLUGINS ___________ Dec 06 19:51:01 volumio volumio[12277]: info: ControllerMpd::onStart: Initializing MPD Dec 06 19:51:01 volumio volumio[12277]: info: Creating MPD Configuration file Dec 06 19:51:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 19:51:01 volumio volumio[12365]: Starting albumart workers Dec 06 19:51:01 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 19:51:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 19:51:01 volumio systemd[1]: mpd.service: Consumed 19.489s CPU time. Dec 06 19:51:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 19:51:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 19:51:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 19:51:01 volumio volumio[12277]: info: [1765021861796] CoreMusicLibrary::Adding element 媒体服务器 Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:01 volumio sudo[12414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 19:51:01 volumio sudo[12414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:01 volumio sudo[12414]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:01 volumio sudo[12416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 19:51:01 volumio sudo[12416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:01 volumio volumio[12366]: Starting albumart workers Dec 06 19:51:01 volumio volumio[12367]: Starting albumart workers Dec 06 19:51:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 19:51:01 volumio volumio[12277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 19:51:01 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 19:51:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 19:51:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 19:51:01 volumio volumio[12277]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 19:51:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 19:51:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 19:51:01 volumio volumio[12277]: info: [1765021861871] CoreMusicLibrary::Adding element Last_100 Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 19:51:01 volumio volumio[12277]: info: [1765021861877] CoreMusicLibrary::Adding element Webradio Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 19:51:01 volumio volumio[12277]: info: Initializing BBC Radios Dec 06 19:51:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 19:51:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 19:51:01 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:01 volumio volumio[12277]: info: Volumio Calling Home Dec 06 19:51:02 volumio sudo[12434]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 19:51:02 volumio sudo[12434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 19:51:02 volumio sudo[12434]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:02 volumio volumio[12277]: info: CoreStateMachine::setRepeat null single undefined Dec 06 19:51:02 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:02 volumio volumio[12277]: info: CoreStateMachine::setRandom null Dec 06 19:51:02 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 06 19:51:02 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Dec 06 19:51:02 volumio volumio[12277]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 06 19:51:02 volumio volumio[12277]: info: MPD Permissions set Dec 06 19:51:02 volumio volumio[12277]: info: MPD Permissions set Dec 06 19:51:02 volumio volumio[12277]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:02 volumio volumio[12277]: info: VolumeController:: Volume=46 Mute =false Dec 06 19:51:02 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:02 volumio volumio[12277]: info: Starting Shairport Sync Dec 06 19:51:02 volumio volumio[12277]: info: Starting Shairport Sync Dec 06 19:51:02 volumio volumio[12277]: info: Starting Shairport Sync Dec 06 19:51:02 volumio sudo[12489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 19:51:02 volumio sudo[12489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:02 volumio sudo[12491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 19:51:02 volumio sudo[12491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:02 volumio volumio[12277]: info: Discovery: adding aabd8f80-ae34-43b4-a6ac-4107e79d5b1a Dec 06 19:51:02 volumio sudo[12493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 19:51:02 volumio volumio[12277]: info: Discovery: Found device Volumio Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio sudo[12493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:02 volumio volumio[12277]: info: Discovery: this is already registered, aabd8f80-ae34-43b4-a6ac-4107e79d5b1a Dec 06 19:51:02 volumio volumio[12277]: info: Discovery: Found device Volumio Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 19:51:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 19:51:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 19:51:02 volumio systemd[1]: shairport-sync.service: Consumed 3.744s CPU time. Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 19:51:02 volumio sudo[12489]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:02 volumio sudo[12491]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:02 volumio volumio[12277]: info: Shairport-Sync Started Dec 06 19:51:02 volumio sudo[12493]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:02 volumio volumio[12277]: Error adding Membership: Error: addMembership EINVAL Dec 06 19:51:02 volumio volumio[12277]: info: Shairport-Sync Started Dec 06 19:51:02 volumio volumio[12277]: info: Shairport-Sync Started Dec 06 19:51:02 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:02 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:02 volumio mpd[12450]: 2025-12-06T19:51:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 19:51:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 19:51:02 volumio sudo[12416]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:02 volumio sudo[12406]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:03 volumio volumio[12277]: info: Completed starting Core Plugins Dec 06 19:51:03 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:51:03 volumio volumio[12277]: info: ----- MyVolumio plugins startup ---- Dec 06 19:51:03 volumio volumio[12277]: info: ------------------------------------------- Dec 06 19:51:03 volumio volumio[12277]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 06 19:51:03 volumio volumio[12277]: error: MPD error: The expression evaluated to a falsy value: Dec 06 19:51:03 volumio volumio[12277]: assert.ok(self.idling) Dec 06 19:51:03 volumio volumio[12277]: error: The expression evaluated to a falsy value: Dec 06 19:51:03 volumio volumio[12277]: assert.ok(self.idling) Dec 06 19:51:03 volumio volumio[12277]: info: MPD running with PID12450 Dec 06 19:51:03 volumio volumio[12277]: ,establishing connection Dec 06 19:51:03 volumio volumio[12277]: error: updateQueue error: null Dec 06 19:51:03 volumio volumio[12277]: error: updateQueue error: null Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:03 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 06 19:51:04 volumio volumio[12277]: info: Volumio called home Dec 06 19:51:04 volumio volumio[12277]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:04 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:04 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 19:51:04 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 19:51:05 volumio volumio[12277]: info: Received Get System Info Dec 06 19:51:05 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 19:51:05 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 19:51:05 volumio volumio[12277]: info: Discovery: Getting this device information Dec 06 19:51:05 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:05 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:05 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 19:51:05 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:05 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:05 volumio volumio[12277]: info: Listing playlists Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:05 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio sudo[12525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 19:51:06 volumio sudo[12525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:06 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 19:51:06 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 19:51:06 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 19:51:06 volumio sudo[12528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 19:51:06 volumio sudo[12528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:06 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 19:51:06 volumio mpd_monitor.sh[12529]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 19:51:06 volumio sudo[12525]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:06 volumio volumio[12277]: info: Successfully started MPD Monitor Dec 06 19:51:06 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 19:51:06 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 19:51:06 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 19:51:06 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 19:51:06 volumio sudo[12528]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:06 volumio mpd_monitor.sh[12533]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 19:51:06 volumio volumio[12277]: info: Successfully started MPD Monitor Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:06 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Dec 06 19:51:07 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:07 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio[12277]: Cannot compose Albumart path Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:08 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio kernel: fat__get_entry: 54 callbacks suppressed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 06 19:51:09 volumio kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:09 volumio sudo[12541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 19:51:09 volumio sudo[12541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:09 volumio sudo[12541]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:09 volumio sudo[12543]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 19:51:09 volumio sudo[12543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:09 volumio sudo[12543]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:09 volumio volumio[12277]: verbose: New Socket.io Connection to 192.168.31.250 from 192.168.31.205 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:09 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 19:51:09 volumio volumio[12277]: info: Received Get System Info Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 19:51:09 volumio volumio[12277]: info: Discovery: Getting this device information Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:09 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:09 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:09 volumio volumio[12277]: info: Listing playlists Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 19:51:09 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:10 volumio sudo[12562]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 19:51:10 volumio sudo[12562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:10 volumio sudo[12564]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 19:51:10 volumio sudo[12562]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:10 volumio sudo[12564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:10 volumio sudo[12564]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:10 volumio sudo[12568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 06 19:51:10 volumio sudo[12568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:10 volumio sudo[12568]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:10 volumio volumio[12277]: info: Upmpdcli Daemon Started Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 19:51:11 volumio volumio[12277]: info: Received Get System Info Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 19:51:11 volumio volumio[12277]: info: Discovery: Getting this device information Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:11 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:11 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin bluetooth to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin multiroom to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin metavolumio to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin cd_controller to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 06 19:51:11 volumio volumio[12277]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 06 19:51:12 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:12 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:12 volumio volumio[12277]: info: Starting MyVolumio Remote Streaming Endpoints Dec 06 19:51:12 volumio volumio[12277]: info: MyVolumio not started Dec 06 19:51:12 volumio volumio[12277]: info: Initializing device activation check Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 06 19:51:12 volumio volumio[12277]: info: Streaming services startup Dec 06 19:51:12 volumio volumio[12277]: info: Starting Streaming Daemon Dec 06 19:51:12 volumio sudo[12573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 06 19:51:12 volumio sudo[12573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 19:51:12 volumio volumio[12277]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 06 19:51:12 volumio sudo[12573]: pam_unix(sudo:session): session closed for user root Dec 06 19:51:12 volumio volumio[12277]: error: Cannot start Volumio Streaming Daemon Dec 06 19:51:12 volumio volumio[12277]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 06 19:51:12 volumio volumio[12277]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:13 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: info: Retrieving Cloud Streaming UI Dec 06 19:51:14 volumio volumio[12277]: info: Getting Tidal Cloud Configuration Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: info: Getting Qobuz Cloud Configuration Dec 06 19:51:14 volumio volumio[12277]: info: Asking plugin for UI Config Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: info: Getting Spotify Cloud Configuration Dec 06 19:51:14 volumio volumio[12277]: info: Asking plugin for UI Config Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: info: Saving Spotify Acccount Dec 06 19:51:14 volumio volumio[12277]: info: Got Tidal Cloud Configuration Dec 06 19:51:14 volumio volumio[12277]: info: Got it Dec 06 19:51:14 volumio volumio[12277]: info: Got it Dec 06 19:51:14 volumio volumio[12277]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:14 volumio volumio[12277]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 19:51:14 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:15 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand rescan Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: info: Dec 06 19:51:16 volumio volumio[12277]: ---------------------------- MPD announces state update: update Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand rescan took 5 milliseconds Dec 06 19:51:16 volumio volumio[12277]: info: ControllerMpd::getState Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 3 milliseconds Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 2 milliseconds Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:16 volumio volumio[12277]: info: Command Router : Notfying DB Updatetrue Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:16 volumio volumio[12277]: info: ControllerMpd::pushState Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::servicePushState Dec 06 19:51:16 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:16 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:16 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:16 volumio volumio[12277]: 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} Dec 06 19:51:16 volumio volumio[12277]: verbose: CURRENT POSITION 0 Dec 06 19:51:16 volumio volumio[12277]: info: CoreStateMachine::syncState stateService stop Dec 06 19:51:16 volumio volumio[12277]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:51:16 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:16 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:16 volumio volumio[12277]: info: No code Dec 06 19:51:16 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:16 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:16 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:16 volumio volumio[12277]: info: ------------------------------ 54ms Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: info: Dec 06 19:51:16 volumio volumio[12277]: ---------------------------- MPD announces state update: update Dec 06 19:51:16 volumio volumio[12277]: info: ControllerMpd::getState Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: info: Dec 06 19:51:16 volumio volumio[12277]: ---------------------------- MPD announces state update: update Dec 06 19:51:16 volumio volumio[12277]: info: ControllerMpd::getState Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand status Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 3 milliseconds Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 2 milliseconds Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 2 milliseconds Dec 06 19:51:16 volumio volumio[12277]: info: sendMpdCommand status took 1 milliseconds Dec 06 19:51:16 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:17 volumio volumio[12277]: info: Command Router : Notfying DB Updatetrue Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:51:17 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:17 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:17 volumio volumio[12277]: info: Command Router : Notfying DB Updatetrue Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:51:17 volumio volumio[12277]: verbose: ControllerMpd::parseState Dec 06 19:51:17 volumio volumio[12277]: info: ControllerMpd::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::servicePushState Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: 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} Dec 06 19:51:17 volumio volumio[12277]: verbose: CURRENT POSITION 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::syncState stateService stop Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: No code Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: ControllerMpd::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::servicePushState Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: 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} Dec 06 19:51:17 volumio volumio[12277]: verbose: CURRENT POSITION 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::syncState stateService stop Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::syncState currentStatus stop Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: No code Dec 06 19:51:17 volumio volumio[12277]: info: CoreStateMachine::pushState Dec 06 19:51:17 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:17 volumio volumio[12277]: info: CoreCommandRouter::volumioPushState Dec 06 19:51:17 volumio volumio[12277]: info: ------------------------------ 82ms Dec 06 19:51:17 volumio volumio[12277]: info: ------------------------------ 82ms Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:17 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:18 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 06 19:51:18 volumio volumio[12277]: verbose: ControllerMpd::sendMpdCommand update Dec 06 19:51:18 volumio volumio[12277]: info: sendMpdCommand update took 1 milliseconds Dec 06 19:51:18 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:19 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:20 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:21 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:22 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:23 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:24 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:25 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: info: Retrieving Cloud Streaming UI Dec 06 19:51:26 volumio volumio[12277]: info: Getting Tidal Cloud Configuration Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: info: Getting Qobuz Cloud Configuration Dec 06 19:51:26 volumio volumio[12277]: info: Asking plugin for UI Config Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: info: Getting Spotify Cloud Configuration Dec 06 19:51:26 volumio volumio[12277]: info: Asking plugin for UI Config Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: info: Saving Spotify Acccount Dec 06 19:51:26 volumio volumio[12277]: info: Got Tidal Cloud Configuration Dec 06 19:51:26 volumio volumio[12277]: info: Got it Dec 06 19:51:26 volumio volumio[12277]: info: Got it Dec 06 19:51:26 volumio volumio[12277]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 19:51:26 volumio volumio[12277]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 19:51:26 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:26 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 19:51:27 volumio volumio[12277]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 06 19:51:27 volumio volumio[12277]: info: CoreCommandRouter::volumioGetState Dec 06 19:51:27 volumio volumio[12277]: info: CorePlayQueue::getTrack 0 Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:27 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:28 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:29 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio[12277]: info: CoreCommandRouter::volumioGetQueue Dec 06 19:51:31 volumio volumio[12277]: info: CoreStateMachine::getQueue Dec 06 19:51:31 volumio volumio[12277]: info: CorePlayQueue::getQueue Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:31 volumio kernel: fat__get_entry: 182 callbacks suppressed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 06 19:51:31 volumio kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E4%B8%80%E4%B8%87%E4%B8%AA%E8%88%8D%E4%B8%8D%E5%BE%97/0a7fae1e-1204-4806-bd8d-47ed0c057234.jpg' Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E4%BF%A1%E5%93%B2/%E4%BB%8E%E5%BC%80%E5%A7%8B%E5%88%B0%E7%8E%B0%E5%9C%A8/3ab0fd62-9222-4348-9fa1-0ff5b197e17e.jpg' Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B5%B5%E9%B9%8F/%E4%BD%8E%E9%9F%B3%E7%8E%8B%E6%9C%9DI%20%E4%BD%A0%E7%9A%84%E7%9C%BC%E7%A5%9E/1e2c493a-afcc-471b-bb5b-40875f8179fa.jpg' Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E6%83%A0%E5%A6%B9/%E4%BD%A0%E5%9C%A8%E7%9C%8B%E6%88%91%E5%90%97/c9571ac7-a45e-44c1-95cf-a420bf296cb9.jpg' Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B5%B5%E4%B9%83%E5%90%89/%E6%9C%80%E5%88%9D%E7%9A%84%E6%B8%A9%E6%9F%94/4fb80004-261f-4b00-bfd3-f42e4dbd2fd2.jpg' Dec 06 19:51:31 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E7%8F%82%E7%9F%A3/%E5%8D%8A%E5%A3%B6%E7%BA%B1/04435367-9214-4904-9cb2-3d37a6a79279.jpg' Dec 06 19:51:31 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 19:51:31 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:31 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%91%A8%E8%95%99/%E5%91%A8%E8%95%99%E7%B2%BE%E9%80%89/e98cb246-4c99-4659-b3f4-c05eef4ba1c8.jpg' Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E5%A5%BD%E5%8F%AF%E6%83%9C/ac9d081b-b4e5-4bd9-b2fe-95035c3d2533.jpg' Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8D%97%E5%A6%AE/%E5%B0%86%E7%88%B1%E5%94%B1%E5%88%B0%E4%BD%A0%E5%BF%83%E9%87%8C/986ee3a2-984b-4098-b18f-87a29f0f3662.jpg' Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BD%95%E4%BB%9F%E4%BB%9F/%E6%83%85%E4%BA%BA%E6%9C%AA%E6%99%9A/9d5121c2-6100-4293-8ffa-0debce7be9c4.jpg' Dec 06 19:51:32 volumio volumio[12277]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 06 19:51:32 volumio volumio[12277]: info: Completed starting MyVolumio Plugin Dec 06 19:51:32 volumio volumio[12277]: [Metrics] CommandRouter: 33s 438.68ms Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::volumiosetStartupVolume Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:51:32 volumio volumio[12277]: info: CoreCommandRouter::Close All Modals sent Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%8F%E5%92%AA/%E6%88%91%E8%B5%B0%E5%90%8E/f5a370be-4e4f-4ab3-885b-35333f0bf311.png' Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%95%E8%83%A7/%E6%8A%8A%E5%AD%A4%E7%8B%AC%E5%BD%93%E5%81%9A%E6%99%9A%E9%A4%90/2e4d4656-f905-4320-b5fd-6bf9065055ea.jpg' Dec 06 19:51:32 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SARA/%E6%8B%9C%E6%8B%9C%C2%B7%E7%88%B1%E8%BF%87/5ef7f666-17ed-4c59-b25a-039d3183367d.jpg' Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BC%A0%E7%8E%AE%E4%BC%BD/%E6%97%A0%E6%83%85%E4%BC%A4/cefff0ee-4718-420b-97b6-d70255a30a2e.jpg' Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%88%B4%E7%BE%BD%E5%BD%A4/%E6%9D%A5%E8%BF%9F/d4bc8f52-4bfb-4c2d-b5ec-2406d965f845.jpg' Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%9B%B7%E5%A9%B7/%E6%AF%8F%E5%BD%93%E6%83%B3%E4%BD%A0%E7%9A%84%E6%97%B6%E5%80%99/17e3cfb9-e03d-46b9-ad55-cfa924ac633b.jpg' Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AD%99%E9%9C%B2/%E7%88%B1%E4%BA%BA%E5%88%AB%E8%B5%B0/27614a9f-4446-429a-9f5d-a726cf0deb4f.jpg' Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E6%83%9C%E5%90%9B/%E7%88%B1%E6%83%85%E8%8A%B1%E5%9B%AD/e616c116-799b-4546-8dbf-619a2c3ad3fa.jpg' Dec 06 19:51:33 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 06 19:51:33 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 19:51:33 volumio volumio[12277]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A3%98%E6%B5%B7%E6%AD%A3/%E7%88%B1%E6%88%91%E7%9A%84%E4%BA%BA%E5%92%8C%E6%88%91%E7%88%B1%E7%9A%84%E4%BA%BA/87b88c75-a9b8-485d-8876-cd2155b89d88.jpg' Dec 06 19:51:33 volumio volumio[12277]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BE%83%E4%BE%83/%E7%88%B1%E6%B7%B1%E8%97%8F/9d35cf1a-79c7-4891-b827-d2f7e662e8fe.jpg' Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio-remote-updater[878]: PROGRESS: 47, STATUS: "Downloading new update", ETA: "4m" Dec 06 19:51:34 volumio volumio[12277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 19:51:34 volumio volumio[12277]: Error: connect ECONNREFUSED 47.88.58.234:443 Dec 06 19:51:34 volumio volumio[12277]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 06 19:51:34 volumio volumio[12277]: errno: -111, Dec 06 19:51:34 volumio volumio[12277]: code: 'ECONNREFUSED', Dec 06 19:51:34 volumio volumio[12277]: syscall: 'connect', Dec 06 19:51:34 volumio volumio[12277]: address: '47.88.58.234', Dec 06 19:51:34 volumio volumio[12277]: port: 443 Dec 06 19:51:34 volumio volumio[12277]: } Dec 06 19:51:34 volumio volumio[12277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 19:51:34 volumio sudo[12672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 19:50' Dec 06 19:51:34 volumio sudo[12672]: 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 12:15:37 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3ca59a0b3ec3a926fd3274a2fb539106"