-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2025-10-29 17:26:57 CST. -- Oct 29 17:25:06 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:15 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:23 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:29 volumio kernel: usb 1-1.3: USB disconnect, device number 4 Oct 29 17:25:30 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Oct 29 17:25:30 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Oct 29 17:25:29 volumio systemd[1]: media-Seagate_Basic.mount: Succeeded. Oct 29 17:25:29 volumio sudo[29002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Oct 29 17:25:30 volumio volumio[1224]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.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","DEVLINKS":"/dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-id/usb-Seagate_Basic_NABS35B5-0:0-part1 /dev/disk/by-partuuid/8e10f8f4-4a06-4d26-a530-52e704833897 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.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":"27","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Basic","ID_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"aa15","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"8e10f8f4-4a06-4d26-a530-52e704833897","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"4ab30737-b1fe-4761-8ae9-37234d22b956","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"0712","ID_SERIAL":"Seagate_Basic_NABS35B5-0:0","ID_SERIAL_SHORT":"NABS35B5","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Seagate","ID_VENDOR_ENC":"Seagate\\x20","ID_VENDOR_ID":"0bc2","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"2406","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14228978138"} Oct 29 17:25:30 volumio volumio[1224]: error: Cannot associate FS Label, not mounting Oct 29 17:25:29 volumio sudo[29002]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 17:25:29 volumio ntfs-3g[28759]: Unmounting /dev/sda2 (Seagate Basic) Oct 29 17:25:30 volumio volumio[1224]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/5133b502-584f-4df9-b2f9-ff26ad943d48 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-uuid/0CF03B2DF03B1C80 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-Seagate_Basic_NABS35B5-0:0-part2 /dev/disk/by-label/Seagate\\x20Basic","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_LABEL":"Seagate_Basic","ID_FS_LABEL_ENC":"Seagate\\x20Basic","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0CF03B2DF03B1C80","ID_FS_UUID_ENC":"0CF03B2DF03B1C80","ID_INSTANCE":"0:0","ID_MODEL":"Basic","ID_MODEL_ENC":"Basic\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"aa15","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"7813771264","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"5133b502-584f-4df9-b2f9-ff26ad943d48","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"4ab30737-b1fe-4761-8ae9-37234d22b956","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"0712","ID_SERIAL":"Seagate_Basic_NABS35B5-0:0","ID_SERIAL_SHORT":"NABS35B5","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Seagate","ID_VENDOR_ENC":"Seagate\\x20","ID_VENDOR_ID":"0bc2","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"2407","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14228978286"} Oct 29 17:25:29 volumio ntfs-3g[28759]: Failed to sync device /dev/sda2: Input/output error Oct 29 17:25:29 volumio ntfs-3g[28759]: Failed to close volume /dev/sda2: Input/output error Oct 29 17:25:29 volumio sudo[29002]: pam_unix(sudo:session): session closed for user root Oct 29 17:25:31 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:33 volumio kernel: usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Oct 29 17:25:33 volumio kernel: usb 2-2: New USB device found, idVendor=0bc2, idProduct=aa15, bcdDevice= 7.12 Oct 29 17:25:33 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 29 17:25:33 volumio kernel: usb 2-2: Product: Basic Oct 29 17:25:33 volumio kernel: usb 2-2: Manufacturer: Seagate Oct 29 17:25:33 volumio kernel: usb 2-2: SerialNumber: NABS35B5 Oct 29 17:25:33 volumio kernel: scsi host0: uas Oct 29 17:25:33 volumio kernel: scsi 0:0:0:0: Direct-Access Seagate Basic 0712 PQ: 0 ANSI: 6 Oct 29 17:25:33 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] 7814037167 512-byte logical blocks: (4.00 TB/3.64 TiB) Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Oct 29 17:25:40 volumio kernel: sda: sda1 sda2 Oct 29 17:25:40 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 29 17:25:40 volumio volumio[1224]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Seagate_Basic Oct 29 17:25:40 volumio volumio[1224]: info: Scanning removed location : "USB/Seagate_Basic" Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces state update: update Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand status took 2 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Oct 29 17:25:40 volumio volumio[1224]: 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} Oct 29 17:25:40 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState stateService stop Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::stPlaybackTimer Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 9ms Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand status took 9 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: info: Command Router : Notfying DB Updatefalse Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::Close All Modals sent Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Oct 29 17:25:40 volumio volumio[1224]: 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} Oct 29 17:25:40 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState stateService stop Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: No code Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 67ms Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: Clearing queue after UPNP request Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::ClearQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::stop Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::clearPlayQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::saveQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushQueue Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: error: updateQueue error: null Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:25:40 volumio volumio[1224]: info: Starting UPNP Playback Oct 29 17:25:40 volumio volumio[1224]: info: Preparing playback through UPNP Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetState Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 6ms Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand status took 6 milliseconds Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand status took 1 milliseconds Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:40 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:25:40 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"橄榄树","artist":"齐豫","album":"橄榄树","uri":"http://aqqmusic.tc.qq.com/M800004ZElWG3pqqDU.mp3?guid=000000007733b2850000019a0efbedd7&vkey=3B7B20FC7706D554164F1E102EB4BB886D7FF37604792E6B90D6F0AFDC5D2456573C4F55793D07B14977C3A215EA077185AB4292B09AE86F__v21ea05d36&uin=1152921504888564180&redirect=1&fromtag=111093","trackType":""} Oct 29 17:25:40 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 8ms Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:40 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:25:40 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"橄榄树","artist":"齐豫","album":"橄榄树","uri":"http://aqqmusic.tc.qq.com/M800004ZElWG3pqqDU.mp3?guid=000000007733b2850000019a0efbedd7&vkey=3B7B20FC7706D554164F1E102EB4BB886D7FF37604792E6B90D6F0AFDC5D2456573C4F55793D07B14977C3A215EA077185AB4292B09AE86F__v21ea05d36&uin=1152921504888564180&redirect=1&fromtag=111093","trackType":""} Oct 29 17:25:40 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:25:40 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 36ms Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:25:40 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:25:40 volumio volumio[1224]: info: Oct 29 17:25:40 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 2ms Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand status took 2 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:25:40 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 29 17:25:40 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:25:40 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:40 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:25:40 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"橄榄树","artist":"齐豫","album":"橄榄树","uri":"http://aqqmusic.tc.qq.com/M800004ZElWG3pqqDU.mp3?guid=000000007733b2850000019a0efbedd7&vkey=3B7B20FC7706D554164F1E102EB4BB886D7FF37604792E6B90D6F0AFDC5D2456573C4F55793D07B14977C3A215EA077185AB4292B09AE86F__v21ea05d36&uin=1152921504888564180&redirect=1&fromtag=111093","trackType":""} Oct 29 17:25:40 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:25:40 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:40 volumio volumio[1224]: info: ------------------------------ 8ms Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:40 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:40 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:40 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:40 volumio volumio[1224]: error: Cannot associate FS Label, not mounting Oct 29 17:25:40 volumio volumio[1224]: info: Mounting Device Seagate_Basic Oct 29 17:25:40 volumio sudo[29065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Seagate_Basic -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 29 17:25:40 volumio sudo[29065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 29 17:25:42 volumio ntfs-3g[29082]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 29 17:25:42 volumio ntfs-3g[29082]: Mounted /dev/sda2 (Read-Write, label "Seagate Basic", NTFS 3.1) Oct 29 17:25:42 volumio ntfs-3g[29082]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 29 17:25:42 volumio ntfs-3g[29082]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Oct 29 17:25:42 volumio ntfs-3g[29082]: Global ownership and permissions enforced, configuration type 7 Oct 29 17:25:42 volumio sudo[29065]: pam_unix(sudo:session): session closed for user root Oct 29 17:25:42 volumio volumio[1224]: info: Scanning new location : "USB/Seagate_Basic" Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:42 volumio volumio[1224]: info: Oct 29 17:25:42 volumio volumio[1224]: ---------------------------- MPD announces state update: update Oct 29 17:25:42 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:25:42 volumio volumio[1224]: info: sendMpdCommand status took 2 milliseconds Oct 29 17:25:42 volumio volumio[1224]: info: sendMpdCommand status took 1 milliseconds Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:42 volumio volumio[1224]: info: Command Router : Notfying DB Updatetrue Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:25:42 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 29 17:25:42 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:25:42 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:25:42 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:25:42 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5257,"duration":224,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"橄榄树","artist":"齐豫","album":"橄榄树","uri":"http://aqqmusic.tc.qq.com/M800004ZElWG3pqqDU.mp3?guid=000000007733b2850000019a0efbedd7&vkey=3B7B20FC7706D554164F1E102EB4BB886D7FF37604792E6B90D6F0AFDC5D2456573C4F55793D07B14977C3A215EA077185AB4292B09AE86F__v21ea05d36&uin=1152921504888564180&redirect=1&fromtag=111093","trackType":""} Oct 29 17:25:42 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:25:42 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:25:42 volumio volumio[1224]: info: ------------------------------ 43ms Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:42 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:42 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:25:42 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:25:42 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:25:48 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:25:57 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:05 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:11 volumio volumio[1224]: info: Oct 29 17:26:11 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:26:11 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:26:11 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:26:11 volumio volumio[1224]: info: sendMpdCommand status took 1 milliseconds Oct 29 17:26:11 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:26:11 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:26:11 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 29 17:26:11 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:26:11 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:11 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Oct 29 17:26:11 volumio volumio[1224]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"橄榄树","artist":"齐豫","album":"橄榄树","uri":"http://aqqmusic.tc.qq.com/M800004ZElWG3pqqDU.mp3?guid=000000007733b2850000019a0efbedd7&vkey=3B7B20FC7706D554164F1E102EB4BB886D7FF37604792E6B90D6F0AFDC5D2456573C4F55793D07B14977C3A215EA077185AB4292B09AE86F__v21ea05d36&uin=1152921504888564180&redirect=1&fromtag=111093","trackType":""} Oct 29 17:26:11 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::syncState stateService stop Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::stPlaybackTimer Oct 29 17:26:11 volumio volumio[1224]: info: ------------------------------ 7ms Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:26:11 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:26:11 volumio volumio[1224]: info: Clearing queue after UPNP request Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::ClearQueue Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::stop Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 17:26:11 volumio volumio[1224]: info: CorePlayQueue::clearPlayQueue Oct 29 17:26:11 volumio volumio[1224]: info: CorePlayQueue::saveQueue Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioPushQueue Oct 29 17:26:11 volumio volumio[1224]: info: Oct 29 17:26:11 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:11 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:11 volumio volumio[1224]: error: updateQueue error: null Oct 29 17:26:11 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:11 volumio volumio[1224]: info: Starting UPNP Playback Oct 29 17:26:11 volumio volumio[1224]: info: Preparing playback through UPNP Oct 29 17:26:11 volumio volumio[1224]: info: CoreCommandRouter::volumioGetState Oct 29 17:26:11 volumio volumio[1224]: info: CorePlayQueue::getTrack 0 Oct 29 17:26:11 volumio volumio[1224]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 1ms Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand status took 4 milliseconds Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand status took 1 milliseconds Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:26:12 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:26:12 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poker Face","artist":"Lady Gaga","album":"The Fame","uri":"http://aqqmusic.tc.qq.com/M800001BQ9D62as9Xp.mp3?guid=000000007733b2850000019a0efbedd7&vkey=B1295D2FA5B9CB24B849A2D5A4AAA7BAD6F3A7B09A731F185F1426F351BEA606B3AE8A8861C624F45330FE5DAD2BDB87F792E2A1037AC751__v21ebdd73e&uin=1152921504888564180&src=M800001cAaoN12Ldj3.mp3&redirect=1&fromtag=111093","trackType":""} Oct 29 17:26:12 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 8ms Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:26:12 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:26:12 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poker Face","artist":"Lady Gaga","album":"The Fame","uri":"http://aqqmusic.tc.qq.com/M800001BQ9D62as9Xp.mp3?guid=000000007733b2850000019a0efbedd7&vkey=B1295D2FA5B9CB24B849A2D5A4AAA7BAD6F3A7B09A731F185F1426F351BEA606B3AE8A8861C624F45330FE5DAD2BDB87F792E2A1037AC751__v21ebdd73e&uin=1152921504888564180&src=M800001cAaoN12Ldj3.mp3&redirect=1&fromtag=111093","trackType":""} Oct 29 17:26:12 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:26:12 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 9ms Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces system playlist update Oct 29 17:26:12 volumio volumio[1224]: info: Ignoring MPD Status Update Oct 29 17:26:12 volumio volumio[1224]: info: Oct 29 17:26:12 volumio volumio[1224]: ---------------------------- MPD announces state update: player Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::getState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 3ms Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand status took 3 milliseconds Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseState Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 17:26:12 volumio volumio[1224]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 29 17:26:12 volumio volumio[1224]: verbose: ControllerMpd::parseTrackInfo Oct 29 17:26:12 volumio volumio[1224]: info: ControllerMpd::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::servicePushState Oct 29 17:26:12 volumio volumio[1224]: verbose: In UPNP mode Oct 29 17:26:12 volumio volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Poker Face","artist":"Lady Gaga","album":"The Fame","uri":"http://aqqmusic.tc.qq.com/M800001BQ9D62as9Xp.mp3?guid=000000007733b2850000019a0efbedd7&vkey=B1295D2FA5B9CB24B849A2D5A4AAA7BAD6F3A7B09A731F185F1426F351BEA606B3AE8A8861C624F45330FE5DAD2BDB87F792E2A1037AC751__v21ebdd73e&uin=1152921504888564180&src=M800001cAaoN12Ldj3.mp3&redirect=1&fromtag=111093","trackType":""} Oct 29 17:26:12 volumio volumio[1224]: verbose: CURRENT POSITION 0 Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState stateService play Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::syncState currentStatus play Oct 29 17:26:12 volumio volumio[1224]: info: Received an update from plugin. extracting info from payload Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::pushState Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioPushState Oct 29 17:26:12 volumio volumio[1224]: info: ------------------------------ 12ms Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreCommandRouter::volumioGetQueue Oct 29 17:26:12 volumio volumio[1224]: info: CoreStateMachine::getQueue Oct 29 17:26:12 volumio volumio[1224]: info: CorePlayQueue::getQueue Oct 29 17:26:13 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:22 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:30 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:39 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:47 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:56 volumio wpa_supplicant[933]: wlan0: Failed to initiate sched scan Oct 29 17:26:56 volumio volumio[1224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 17:26:56 volumio volumio[1224]: Error: connect ETIMEDOUT 199.16.158.190:443 Oct 29 17:26:56 volumio volumio[1224]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 29 17:26:56 volumio volumio[1224]: errno: -110, Oct 29 17:26:56 volumio volumio[1224]: code: 'ETIMEDOUT', Oct 29 17:26:56 volumio volumio[1224]: syscall: 'connect', Oct 29 17:26:56 volumio volumio[1224]: address: '199.16.158.190', Oct 29 17:26:56 volumio volumio[1224]: port: 443 Oct 29 17:26:56 volumio volumio[1224]: } Oct 29 17:26:56 volumio volumio[1224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 17:26:57 volumio sudo[29423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 17:25 Oct 29 17:26:57 volumio sudo[29423]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"