-- Logs begin at Fri 2024-07-12 12:36:06 UTC, end at Fri 2024-07-12 12:44:04 UTC. -- Jul 12 12:43:00 volumio volumio[887]: info: CoreCommandRouter::volumioGetQueue Jul 12 12:43:00 volumio volumio[887]: info: CoreStateMachine::getQueue Jul 12 12:43:00 volumio volumio[887]: info: CorePlayQueue::getQueue Jul 12 12:43:01 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:02 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:43:02 volumio volumio[887]: info: CURURI: artists:// Jul 12 12:43:03 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:43:03 volumio volumio[887]: info: CURURI: music-library Jul 12 12:43:03 volumio volumio[887]: error: Failed LSINFO: null Jul 12 12:43:06 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:06 volumio kernel: usb usb2-port2: attempt power cycle Jul 12 12:43:09 volumio hotspot[883]: wlan0: STA 92:96:f8:53:6f:e3 IEEE 802.11: disassociated Jul 12 12:43:09 volumio hotspot[883]: wlan0: AP-STA-DISCONNECTED 92:96:f8:53:6f:e3 Jul 12 12:43:09 volumio hostapd[917]: wlan0: STA 92:96:f8:53:6f:e3 IEEE 802.11: disassociated Jul 12 12:43:09 volumio hostapd[917]: wlan0: STA 92:96:f8:53:6f:e3 IEEE 802.11: disassociated Jul 12 12:43:09 volumio hotspot[883]: wlan0: STA 92:96:f8:53:6f:e3 IEEE 802.11: disassociated Jul 12 12:43:10 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:15 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:15 volumio kernel: usb usb2-port2: unable to enumerate USB device Jul 12 12:43:15 volumio kernel: usb 2-1: new SuperSpeed Gen 1 USB device number 42 using xhci_hcd Jul 12 12:43:15 volumio kernel: usb 2-1: New USB device found, idVendor=125f, idProduct=312b, bcdDevice= 1.00 Jul 12 12:43:15 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 12 12:43:15 volumio kernel: usb 2-1: Product: ADATA USB Flash Drive Jul 12 12:43:15 volumio kernel: usb 2-1: Manufacturer: ADATA Jul 12 12:43:15 volumio kernel: usb 2-1: SerialNumber: 1461318462330034 Jul 12 12:43:15 volumio kernel: usb-storage 2-1:1.0: USB Mass Storage device detected Jul 12 12:43:15 volumio kernel: scsi host0: usb-storage 2-1:1.0 Jul 12 12:43:16 volumio kernel: scsi 0:0:0:0: Direct-Access ADATA USB Flash Drive 0.00 PQ: 0 ANSI: 6 Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: [sda] 30344192 512-byte logical blocks: (15.5 GB/14.5 GiB) Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Jul 12 12:43:16 volumio kernel: sda: sda1 Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Jul 12 12:43:16 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Jul 12 12:43:16 volumio udisks-glue[590]: Device file /dev/sda inserted Jul 12 12:43:16 volumio udisks-glue[590]: Device file /dev/sda1 inserted Jul 12 12:43:16 volumio udisks-glue[590]: Trying to automount /dev/sda1... Jul 12 12:43:16 volumio udisks-glue[590]: Successfully automounted /dev/sda1 at /media/ADATA UFD Jul 12 12:43:16 volumio udisks-glue[590]: Device file /dev/sda1 mounted at /media/ADATA UFD Jul 12 12:43:16 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:16 volumio volumio[887]: info: Jul 12 12:43:16 volumio volumio[887]: ---------------------------- MPD announces state update: update Jul 12 12:43:16 volumio udisks-glue[590]: Updating DB (#1) ... Jul 12 12:43:16 volumio udisks-glue[590]: volume:100% repeat: off random: off single: off consume: off Jul 12 12:43:16 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:16 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:16 volumio volumio[887]: info: sendMpdCommand status took 5 milliseconds Jul 12 12:43:16 volumio volumio[887]: info: sendMpdCommand status took 2 milliseconds Jul 12 12:43:16 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:17 volumio volumio[887]: info: Command Router : Notfying DB Updatetrue Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:17 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: 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} Jul 12 12:43:17 volumio volumio[887]: verbose: CURRENT POSITION 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState stateService stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: No code Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: ------------------------------ 89ms Jul 12 12:43:17 volumio volumio[887]: info: MPD Database updated - AlbumList cache refreshed Jul 12 12:43:17 volumio volumio[887]: info: Jul 12 12:43:17 volumio volumio[887]: ---------------------------- MPD announces state update: database Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:17 volumio volumio[887]: info: Jul 12 12:43:17 volumio volumio[887]: ---------------------------- MPD announces state update: update Jul 12 12:43:17 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:17 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:17 volumio volumio[887]: info: sendMpdCommand status took 11 milliseconds Jul 12 12:43:17 volumio volumio[887]: info: sendMpdCommand status took 10 milliseconds Jul 12 12:43:17 volumio volumio[887]: info: sendMpdCommand status took 10 milliseconds Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:17 volumio volumio[887]: info: Command Router : Notfying DB Updatefalse Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::Close All Modals sent Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:17 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:17 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: 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} Jul 12 12:43:17 volumio volumio[887]: verbose: CURRENT POSITION 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState stateService stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: No code Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: 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} Jul 12 12:43:17 volumio volumio[887]: verbose: CURRENT POSITION 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState stateService stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: No code Jul 12 12:43:17 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:17 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:17 volumio volumio[887]: info: ------------------------------ 64ms Jul 12 12:43:17 volumio volumio[887]: info: ------------------------------ 65ms Jul 12 12:43:19 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:22 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:43:22 volumio volumio[887]: info: CURURI: music-library Jul 12 12:43:24 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:28 volumio kernel: usb usb2-port2: Cannot enable. Maybe the USB cable is bad? Jul 12 12:43:28 volumio kernel: usb usb2-port2: attempt power cycle Jul 12 12:43:29 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:43:29 volumio volumio[887]: info: CURURI: music-library/USB Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::ClearQueue Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::stop Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:31 volumio volumio[887]: UNSET VOLATILE Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::clearPlayQueue Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::saveQueue Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushQueue Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::addQueueItems Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::addQueueItems Jul 12 12:43:31 volumio volumio[887]: info: Adding Item to queue: music-library/USB/ADATA UFD Jul 12 12:43:31 volumio volumio[887]: info: Exploding uri music-library/USB/ADATA UFD in service mpd Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Leo%20Rojas/Das%20Beste/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20Amigos.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - Amigos.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20Celeste.mp3&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - Celeste.mp3 Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20Colors%20Of%20The%20Rainbow.mp3&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - Colors Of The Rainbow.mp3 Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Leo%20Rojas/Spirit%20of%20the%20Hawk/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20Der%20einsame%20Hirte.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - Der einsame Hirte.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Leo%20Rojas/Das%20Beste/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20Der%20mit%20dem%20Wolf%20tanzt.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - Der mit dem Wolf tanzt.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Leo%20Rojas/Das%20Beste/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20En%20Aranjuez%20Con%20Tu%20Amor.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - En Aranjuez Con Tu Amor.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FLeo%20Rojas%20-%20We%20Are%20the%20World.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Leo Rojas - We Are the World.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Meteora//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FMe.Te.Ora.%20-%20Cioccolato.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Me.Te.Ora. - Cioccolato.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FRachele%20Ginovessi%20-%20Meteora.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Rachele Ginovessi - Meteora.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=%E7%90%86%E6%9F%A5%E5%BE%B7.%E5%85%8B%E8%8E%B1%E5%BE%B7%E6%9B%BC/%E9%92%A2%E7%90%B4%E8%BD%BB%E9%9F%B3%E4%B9%90%E6%9B%B2%E9%80%89/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FRichard%20Clayderman%20-%20%E7%BD%97%E5%AF%86%E6%AC%A7%E4%B8%8E%E6%9C%B1%E4%B8%BD%E5%8F%B6%20(%E9%92%A2%E7%90%B4%E6%9B%B2).flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Richard Clayderman - 罗密欧与朱丽叶 (钢琴曲).flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=Sofia%20Metropolitan%20Orchestra/Great%20Masterpieces%2C%20Live%20Recordings%20Vol.%20%202/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2FSofia%20Metropolitan%20Orchestra%20-%20Leichte%20Kavallerie%20Overture%20Franz%20von%20Suppe.mp3&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/Sofia Metropolitan Orchestra - Leichte Kavallerie Overture Franz von Suppe.mp3 Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=%E7%99%BD%E6%99%93/%E5%AF%82%E5%AF%9E%E4%BC%A4%E6%83%85/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E4%B8%A2%E4%BA%86%E5%B9%B8%E7%A6%8F%E7%9A%84%E7%8C%AA.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 丢了幸福的猪.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E4%BB%8A%E5%A4%9C%E4%BD%A0%E4%BC%9A%E4%B8%8D%E4%BC%9A%E6%9D%A5.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 今夜你会不会来.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E4%BC%9A%E5%91%BC%E5%90%B8%E7%9A%84%E7%97%9B.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 会呼吸的痛.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E5%81%87%E5%A6%82%E7%88%B1%E6%9C%89%E5%A4%A9%E6%84%8F.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 假如爱有天意.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E5%AF%B9%E4%BD%A0.mp3&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 对你.mp3 Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E6%A2%A6%E4%B8%80%E5%9C%BA.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 梦一场.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=%E7%99%BD%E6%99%93/%E5%AF%82%E5%AF%9E%E4%BC%A4%E6%83%85/extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E7%8C%9C%E4%B8%8D%E9%80%8F.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 猜不透.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E7%BA%A2%E9%9B%A8.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 红雨.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E7%BB%99%E6%88%91%E4%B8%80%E4%B8%AA%E7%90%86%E7%94%B1%E5%BF%98%E8%AE%B0.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 给我一个理由忘记.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E9%83%BD%E6%98%AF%E5%A4%9C%E5%BD%92%E4%BA%BA.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 都是夜归人.flac Jul 12 12:43:31 volumio volumio[887]: info: ALBUMART /albumart?cacheid=901&web=//extralarge&path=%2Fmnt%2FUSB%2FADATA%20UFD%2F%E7%99%BD%E6%99%93%20-%20%E9%BB%98.flac&metadata=false Jul 12 12:43:31 volumio volumio[887]: info: URI /mnt/USB/ADATA UFD/白晓 - 默.flac Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::saveQueue Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushQueue Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::updateTrackBlock Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrackBlock Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPlay Jul 12 12:43:31 volumio volumio[887]: UNSET VOLATILE Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::play index 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::stop Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:31 volumio volumio[887]: UNSET VOLATILE Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::play index undefined Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::startPlaybackTimer Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::clearAddPlayTracks USB/ADATA UFD/Leo Rojas - Amigos.flac Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand stop took 3 milliseconds Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 12:43:31 volumio volumio[887]: info: Jul 12 12:43:31 volumio volumio[887]: ---------------------------- MPD announces system playlist update Jul 12 12:43:31 volumio volumio[887]: info: Ignoring MPD Status Update Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand clear took 4 milliseconds Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand add "USB/ADATA UFD/Leo Rojas - Amigos.flac" Jul 12 12:43:31 volumio volumio[887]: error: updateQueue error: null Jul 12 12:43:31 volumio volumio[887]: info: Jul 12 12:43:31 volumio volumio[887]: ---------------------------- MPD announces system playlist update Jul 12 12:43:31 volumio volumio[887]: info: Ignoring MPD Status Update Jul 12 12:43:31 volumio volumio[887]: info: ------------------------------ 3ms Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand add "USB/ADATA UFD/Leo Rojas - Amigos.flac" took 2 milliseconds Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand play Jul 12 12:43:31 volumio volumio[887]: info: Jul 12 12:43:31 volumio volumio[887]: ---------------------------- MPD announces state update: player Jul 12 12:43:31 volumio volumio[887]: info: ------------------------------ 8ms Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand play took 7 milliseconds Jul 12 12:43:31 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:31 volumio kernel: usb 1-1.4: 1:1: cannot get freq at ep 0x1 Jul 12 12:43:31 volumio volumio[887]: info: Jul 12 12:43:31 volumio volumio[887]: ---------------------------- MPD announces state update: player Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand status took 8 milliseconds Jul 12 12:43:31 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand status took 2 milliseconds Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Jul 12 12:43:31 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03 Amigos","artist":"Leo Rojas","album":"Das Beste","uri":"USB/ADATA UFD/Leo Rojas - Amigos.flac","trackType":"flac"} Jul 12 12:43:31 volumio volumio[887]: verbose: CURRENT POSITION 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::syncState stateService play Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:31 volumio volumio[887]: info: ------------------------------ 22ms Jul 12 12:43:31 volumio volumio[887]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 12 12:43:31 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Jul 12 12:43:31 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"03 Amigos","artist":"Leo Rojas","album":"Das Beste","uri":"USB/ADATA UFD/Leo Rojas - Amigos.flac","trackType":"flac"} Jul 12 12:43:31 volumio volumio[887]: verbose: CURRENT POSITION 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::syncState stateService play Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus play Jul 12 12:43:31 volumio volumio[887]: info: Received an update from plugin. extracting info from payload Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:31 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:31 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:31 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:31 volumio volumio[887]: info: ------------------------------ 23ms Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume5 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume15 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume25 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume35 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume45 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:49 volumio volumio[887]: info: VolumeController::SetAlsaVolume50 Jul 12 12:43:49 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:49 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:50 volumio volumio[887]: info: VolumeController::SetAlsaVolume60 Jul 12 12:43:50 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:50 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:50 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:50 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:51 volumio volumio[887]: info: VolumeController::SetAlsaVolume65 Jul 12 12:43:51 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:51 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:51 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:51 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:51 volumio volumio[887]: info: VolumeController::SetAlsaVolume80 Jul 12 12:43:51 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:51 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:51 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:51 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:52 volumio volumio[887]: info: VolumeController::SetAlsaVolume85 Jul 12 12:43:52 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:52 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:52 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:52 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:52 volumio volumio[887]: info: VolumeController::SetAlsaVolume90 Jul 12 12:43:52 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:52 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:52 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:52 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:54 volumio volumio[887]: info: CoreCommandRouter::volumioNext Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::next Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::stop Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:54 volumio volumio[887]: UNSET VOLATILE Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::stPlaybackTimer Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::updateTrackBlock Jul 12 12:43:54 volumio volumio[887]: info: CorePlayQueue::getTrackBlock Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:54 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:54 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:54 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:54 volumio volumio[887]: info: CoreStateMachine::serviceStop Jul 12 12:43:54 volumio volumio[887]: info: CorePlayQueue::getTrack 0 Jul 12 12:43:54 volumio volumio[887]: info: CoreCommandRouter::serviceStop Jul 12 12:43:54 volumio volumio[887]: info: ControllerMpd::stop Jul 12 12:43:54 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand stop took 54 milliseconds Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::play index undefined Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::startPlaybackTimer Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::clearAddPlayTracks USB/ADATA UFD/Leo Rojas - Celeste.mp3 Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::updateTrackBlock Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrackBlock Jul 12 12:43:55 volumio volumio[887]: info: Jul 12 12:43:55 volumio volumio[887]: ---------------------------- MPD announces state update: player Jul 12 12:43:55 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand stop took 4 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 12:43:55 volumio volumio[887]: info: Jul 12 12:43:55 volumio volumio[887]: ---------------------------- MPD announces system playlist update Jul 12 12:43:55 volumio volumio[887]: info: Ignoring MPD Status Update Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand status took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand clear took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand add "USB/ADATA UFD/Leo Rojas - Celeste.mp3" Jul 12 12:43:55 volumio volumio[887]: error: updateQueue error: null Jul 12 12:43:55 volumio volumio[887]: info: Jul 12 12:43:55 volumio volumio[887]: ---------------------------- MPD announces system playlist update Jul 12 12:43:55 volumio volumio[887]: info: Ignoring MPD Status Update Jul 12 12:43:55 volumio volumio[887]: info: ------------------------------ 3ms Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand add "USB/ADATA UFD/Leo Rojas - Celeste.mp3" took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand play Jul 12 12:43:55 volumio volumio[887]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 12 12:43:55 volumio volumio[887]: info: ------------------------------ 8ms Jul 12 12:43:55 volumio volumio[887]: info: ------------------------------ 4ms Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand play took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: info: Jul 12 12:43:55 volumio volumio[887]: ---------------------------- MPD announces state update: player Jul 12 12:43:55 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand status took 4 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 12:43:55 volumio volumio[887]: info: Jul 12 12:43:55 volumio volumio[887]: ---------------------------- MPD announces state update: player Jul 12 12:43:55 volumio volumio[887]: info: ControllerMpd::getState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Jul 12 12:43:55 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Leo Rojas - Celeste.mp3","artist":null,"album":null,"uri":"USB/ADATA UFD/Leo Rojas - Celeste.mp3","trackType":"mp3"} Jul 12 12:43:55 volumio volumio[887]: verbose: CURRENT POSITION 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::syncState stateService play Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus stop Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:55 volumio volumio[887]: info: ------------------------------ 13ms Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand status took 7 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseState Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 12:43:55 volumio volumio[887]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 12:43:55 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Jul 12 12:43:55 volumio volumio[887]: info: ControllerMpd::pushState Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::servicePushState Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: verbose: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":245,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Leo Rojas - Celeste.mp3","artist":null,"album":null,"uri":"USB/ADATA UFD/Leo Rojas - Celeste.mp3","trackType":"mp3"} Jul 12 12:43:55 volumio volumio[887]: verbose: CURRENT POSITION 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::syncState stateService play Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus play Jul 12 12:43:55 volumio volumio[887]: info: Received an update from plugin. extracting info from payload Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:55 volumio volumio[887]: info: CoreStateMachine::pushState Jul 12 12:43:55 volumio volumio[887]: info: CorePlayQueue::getTrack 1 Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Jul 12 12:43:55 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 12:43:55 volumio volumio[887]: info: ------------------------------ 21ms Jul 12 12:44:00 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:44:00 volumio volumio[887]: info: CURURI: playlists Jul 12 12:44:00 volumio volumio[887]: info: Listing playlists Jul 12 12:44:02 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:44:02 volumio volumio[887]: info: CURURI: playlists Jul 12 12:44:02 volumio volumio[887]: info: Listing playlists Jul 12 12:44:02 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 12 12:44:02 volumio volumio[887]: info: CURURI: playlists Jul 12 12:44:02 volumio volumio[887]: info: Listing playlists Jul 12 12:44:04 volumio volumio[887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 12:44:04 volumio volumio[887]: { Error: connect ETIMEDOUT 69.63.181.12:443 Jul 12 12:44:04 volumio volumio[887]: at Object._errnoException (util.js:1022:11) Jul 12 12:44:04 volumio volumio[887]: at _exceptionWithHostPort (util.js:1044:20) Jul 12 12:44:04 volumio volumio[887]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 12 12:44:04 volumio volumio[887]: code: 'ETIMEDOUT', Jul 12 12:44:04 volumio volumio[887]: errno: 'ETIMEDOUT', Jul 12 12:44:04 volumio volumio[887]: syscall: 'connect', Jul 12 12:44:04 volumio volumio[887]: address: '69.63.181.12', Jul 12 12:44:04 volumio volumio[887]: port: 443 } Jul 12 12:44:04 volumio volumio[887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 12:44:04 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 12:43 Jul 12 12:44:04 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"