-- Logs begin at Wed 2025-09-10 11:20:28 UTC, end at Wed 2025-09-10 13:23:03 UTC. -- Sep 10 13:22:13 volumio kernel: usb 1-1.4: new high-speed USB device number 5 using dwc_otg Sep 10 13:22:14 volumio kernel: usb 1-1.4: New USB device found, idVendor=0951, idProduct=1665, bcdDevice= 1.00 Sep 10 13:22:14 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 10 13:22:14 volumio kernel: usb 1-1.4: Product: DataTraveler 2.0 Sep 10 13:22:14 volumio kernel: usb 1-1.4: Manufacturer: Kingston Sep 10 13:22:14 volumio kernel: usb 1-1.4: SerialNumber: 001CC05FE932BD40D9522216 Sep 10 13:22:14 volumio kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Sep 10 13:22:14 volumio kernel: scsi host0: usb-storage 1-1.4:1.0 Sep 10 13:22:15 volumio kernel: scsi 0:0:0:0: Direct-Access Kingston DataTraveler 2.0 PMAP PQ: 0 ANSI: 4 Sep 10 13:22:15 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] 15147008 512-byte logical blocks: (7.76 GB/7.22 GiB) Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Sep 10 13:22:16 volumio kernel: sda: sda1 Sep 10 13:22:16 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 10 13:22:16 volumio udisks-glue[616]: Device file /dev/sda inserted Sep 10 13:22:16 volumio udisks-glue[616]: Device file /dev/sda1 inserted Sep 10 13:22:16 volumio udisks-glue[616]: Trying to automount /dev/sda1... Sep 10 13:22:16 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 10 13:22:16 volumio udisks-glue[616]: Successfully automounted /dev/sda1 at /media/EE9B-3081 Sep 10 13:22:16 volumio udisks-glue[616]: Device file /dev/sda1 mounted at /media/EE9B-3081 Sep 10 13:22:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:16 volumio volumio[1000]: info: Sep 10 13:22:16 volumio volumio[1000]: ---------------------------- MPD announces state update: update Sep 10 13:22:16 volumio volumio[1000]: info: ControllerMpd::getState Sep 10 13:22:16 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:16 volumio udisks-glue[616]: 孟庭苇 - 羞答答的玫瑰静悄悄地开 Sep 10 13:22:16 volumio udisks-glue[616]: [playing] #1/1 2:32/4:24 (57%) Sep 10 13:22:16 volumio udisks-glue[616]: Updating DB (#5) ... Sep 10 13:22:16 volumio udisks-glue[616]: volume:100% repeat: off random: off single: off consume: on Sep 10 13:22:16 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 10 13:22:16 volumio volumio[1000]: info: sendMpdCommand status took 7 milliseconds Sep 10 13:22:16 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 10 13:22:18 volumio volumio[1000]: info: Command Router : Notfying DB Updatetrue Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::Close All Modals sent Sep 10 13:22:18 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 10 13:22:18 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 13:22:18 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 10 13:22:18 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 10 13:22:18 volumio volumio[1000]: info: ControllerMpd::pushState Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 10 13:22:18 volumio volumio[1000]: verbose: In UPNP mode Sep 10 13:22:18 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152067,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"羞答答的玫瑰静悄悄地开","artist":"孟庭苇","album":"谁的眼泪在飞","uri":"http://aqqmusic.tc.qq.com/F000001Vcfjb3RoF7O.flac?guid=ffffffffc73fb5da00000198a8fbcf9f&vkey=C79E954AAD42EB589FBB40870615D6210CA406791AD56F4A4DCA35D3A6E80F50796337B09CF040C52EB645B9E1D32F44B5ACADB7C3BFACA2__v2b9ab161&uin=1152921504869461316&redirect=1&fromtag=111114","trackType":""} Sep 10 13:22:18 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus play Sep 10 13:22:18 volumio volumio[1000]: info: Received an update from plugin. extracting info from payload Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:18 volumio volumio[1000]: info: ------------------------------ 1476ms Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:18 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:18 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:18 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:18 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: MPD Database updated - AlbumList cache refreshed Sep 10 13:22:21 volumio volumio[1000]: info: Sep 10 13:22:21 volumio volumio[1000]: ---------------------------- MPD announces state update: database Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:21 volumio volumio[1000]: info: Sep 10 13:22:21 volumio volumio[1000]: ---------------------------- MPD announces state update: update Sep 10 13:22:21 volumio volumio[1000]: info: ControllerMpd::getState Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:21 volumio volumio[1000]: info: ControllerMpd::getState Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:21 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 10 13:22:21 volumio volumio[1000]: info: sendMpdCommand status took 5 milliseconds Sep 10 13:22:21 volumio volumio[1000]: info: sendMpdCommand status took 4 milliseconds Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 10 13:22:21 volumio volumio[1000]: info: Command Router : Notfying DB Updatefalse Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::Close All Modals sent Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::parseState Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 13:22:21 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 10 13:22:21 volumio volumio[1000]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 10 13:22:21 volumio volumio[1000]: verbose: ControllerMpd::parseTrackInfo Sep 10 13:22:21 volumio volumio[1000]: info: ControllerMpd::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 10 13:22:21 volumio volumio[1000]: verbose: In UPNP mode Sep 10 13:22:21 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156757,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"855 Kbps","isStreaming":false,"title":"羞答答的玫瑰静悄悄地开","artist":"孟庭苇","album":"谁的眼泪在飞","uri":"http://aqqmusic.tc.qq.com/F000001Vcfjb3RoF7O.flac?guid=ffffffffc73fb5da00000198a8fbcf9f&vkey=C79E954AAD42EB589FBB40870615D6210CA406791AD56F4A4DCA35D3A6E80F50796337B09CF040C52EB645B9E1D32F44B5ACADB7C3BFACA2__v2b9ab161&uin=1152921504869461316&redirect=1&fromtag=111114","trackType":""} Sep 10 13:22:21 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus play Sep 10 13:22:21 volumio volumio[1000]: info: Received an update from plugin. extracting info from payload Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:21 volumio volumio[1000]: info: ControllerMpd::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Sep 10 13:22:21 volumio volumio[1000]: verbose: In UPNP mode Sep 10 13:22:21 volumio volumio[1000]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156757,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"855 Kbps","isStreaming":false,"title":"羞答答的玫瑰静悄悄地开","artist":"孟庭苇","album":"谁的眼泪在飞","uri":"http://aqqmusic.tc.qq.com/F000001Vcfjb3RoF7O.flac?guid=ffffffffc73fb5da00000198a8fbcf9f&vkey=C79E954AAD42EB589FBB40870615D6210CA406791AD56F4A4DCA35D3A6E80F50796337B09CF040C52EB645B9E1D32F44B5ACADB7C3BFACA2__v2b9ab161&uin=1152921504869461316&redirect=1&fromtag=111114","trackType":""} Sep 10 13:22:21 volumio volumio[1000]: verbose: CURRENT POSITION 0 Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::syncState stateService play Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::syncState currentStatus play Sep 10 13:22:21 volumio volumio[1000]: info: Received an update from plugin. extracting info from payload Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::pushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:21 volumio volumio[1000]: info: ------------------------------ 89ms Sep 10 13:22:21 volumio volumio[1000]: info: ------------------------------ 89ms Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:21 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:21 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:29 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:29 volumio volumio[1000]: info: CoreCommandRouter::volumioGetQueue Sep 10 13:22:29 volumio volumio[1000]: info: CoreStateMachine::getQueue Sep 10 13:22:29 volumio volumio[1000]: info: CorePlayQueue::getQueue Sep 10 13:22:29 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 10 13:22:29 volumio volumio[1000]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 13:22:29 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 13:22:29 volumio volumio[1000]: info: Listing playlists Sep 10 13:22:57 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 10 13:22:57 volumio volumio[1000]: info: CURURI: music-library Sep 10 13:22:59 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 10 13:22:59 volumio volumio[1000]: info: CURURI: music-library/USB Sep 10 13:23:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 10 13:23:01 volumio volumio[1000]: info: CURURI: music-library/USB/EE9B-3081 Sep 10 13:23:02 volumio volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 13:23:02 volumio volumio[1000]: { Error: connect ETIMEDOUT 108.160.162.102:443 Sep 10 13:23:02 volumio volumio[1000]: at Object._errnoException (util.js:1022:11) Sep 10 13:23:02 volumio volumio[1000]: at _exceptionWithHostPort (util.js:1044:20) Sep 10 13:23:02 volumio volumio[1000]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 10 13:23:02 volumio volumio[1000]: code: 'ETIMEDOUT', Sep 10 13:23:02 volumio volumio[1000]: errno: 'ETIMEDOUT', Sep 10 13:23:02 volumio volumio[1000]: syscall: 'connect', Sep 10 13:23:02 volumio volumio[1000]: address: '108.160.162.102', Sep 10 13:23:02 volumio volumio[1000]: port: 443 } Sep 10 13:23:02 volumio volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 13:23:03 volumio sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 13:22 Sep 10 13:23:03 volumio sudo[5693]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"