-- Logs begin at Thu 2024-10-03 13:10:07 CST, end at Thu 2024-10-03 13:15:02 CST. -- Oct 03 13:14:00 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:04 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:08 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:11 volumio kernel: usb 5-2: new high-speed USB device number 3 using xhci_hcd Oct 03 13:14:11 volumio kernel: usb 5-2: New USB device found, idVendor=058f, idProduct=6387, bcdDevice= 1.00 Oct 03 13:14:11 volumio kernel: usb 5-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 03 13:14:11 volumio kernel: usb 5-2: Product: Mass Storage Oct 03 13:14:11 volumio kernel: usb 5-2: Manufacturer: Generic Oct 03 13:14:11 volumio kernel: usb 5-2: SerialNumber: E7CF7BCE Oct 03 13:14:11 volumio kernel: usb-storage 5-2:1.0: USB Mass Storage device detected Oct 03 13:14:11 volumio kernel: scsi host2: usb-storage 5-2:1.0 Oct 03 13:14:11 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:11 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:12 volumio kernel: scsi 2:0:0:0: Direct-Access Generic Flash Disk 8.07 PQ: 0 ANSI: 4 Oct 03 13:14:12 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Oct 03 13:14:12 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:13 volumio kernel: sd 2:0:0:0: [sdb] 122880000 512-byte logical blocks: (62.9 GB/58.6 GiB) Oct 03 13:14:13 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off Oct 03 13:14:13 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 23 00 00 00 Oct 03 13:14:13 volumio kernel: sd 2:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 03 13:14:13 volumio kernel: sdb: sdb1 Oct 03 13:14:13 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI removable disk Oct 03 13:14:13 volumio volumio[1033]: info: Mounting Device E229-33CA Oct 03 13:14:13 volumio sudo[2361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/E229-33CA -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 03 13:14:13 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 13:14:13 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 03 13:14:13 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Oct 03 13:14:13 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 03 13:14:13 volumio volumio[1033]: info: Scanning new location : "USB/E229-33CA" Oct 03 13:14:13 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:13 volumio volumio[1033]: info: Oct 03 13:14:13 volumio volumio[1033]: ---------------------------- MPD announces state update: update Oct 03 13:14:13 volumio volumio[1033]: info: ControllerMpd::getState Oct 03 13:14:13 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:13 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Oct 03 13:14:13 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:14:13 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:13 volumio volumio[1033]: info: Command Router : Notfying DB Updatetrue Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::Close All Modals sent Oct 03 13:14:13 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:13 volumio volumio[1033]: info: ControllerMpd::pushState Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Oct 03 13:14:13 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:13 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:13 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:13 volumio volumio[1033]: 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} Oct 03 13:14:13 volumio volumio[1033]: verbose: CURRENT POSITION 0 Oct 03 13:14:13 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Oct 03 13:14:13 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:14:13 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:13 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:13 volumio volumio[1033]: info: No code Oct 03 13:14:13 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:13 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:13 volumio volumio[1033]: info: ------------------------------ 103ms Oct 03 13:14:13 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 03 13:14:13 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand rescan Oct 03 13:14:13 volumio volumio[1033]: info: sendMpdCommand rescan took 5 milliseconds Oct 03 13:14:16 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:20 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:23 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:23 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:24 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:28 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:32 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: MPD Database updated - AlbumList cache refreshed Oct 03 13:14:34 volumio volumio[1033]: info: Oct 03 13:14:34 volumio volumio[1033]: ---------------------------- MPD announces state update: database Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:34 volumio volumio[1033]: info: Oct 03 13:14:34 volumio volumio[1033]: ---------------------------- MPD announces state update: update Oct 03 13:14:34 volumio volumio[1033]: info: ControllerMpd::getState Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:34 volumio volumio[1033]: info: ControllerMpd::getState Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:34 volumio volumio[1033]: info: sendMpdCommand status took 124 milliseconds Oct 03 13:14:34 volumio volumio[1033]: info: sendMpdCommand status took 124 milliseconds Oct 03 13:14:34 volumio volumio[1033]: info: sendMpdCommand status took 123 milliseconds Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:34 volumio volumio[1033]: info: Command Router : Notfying DB Updatetrue Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::Close All Modals sent Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:34 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:34 volumio volumio[1033]: info: ControllerMpd::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: 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} Oct 03 13:14:34 volumio volumio[1033]: verbose: CURRENT POSITION 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: No code Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: ControllerMpd::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: 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} Oct 03 13:14:34 volumio volumio[1033]: verbose: CURRENT POSITION 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: No code Oct 03 13:14:34 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:34 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:34 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:34 volumio volumio[1033]: info: ------------------------------ 252ms Oct 03 13:14:34 volumio volumio[1033]: info: ------------------------------ 253ms Oct 03 13:14:36 volumio volumio[1033]: info: MPD Database updated - AlbumList cache refreshed Oct 03 13:14:36 volumio volumio[1033]: info: Oct 03 13:14:36 volumio volumio[1033]: ---------------------------- MPD announces state update: database Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:36 volumio volumio[1033]: info: Oct 03 13:14:36 volumio volumio[1033]: ---------------------------- MPD announces state update: update Oct 03 13:14:36 volumio volumio[1033]: info: ControllerMpd::getState Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:36 volumio volumio[1033]: info: ControllerMpd::getState Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:14:36 volumio volumio[1033]: info: sendMpdCommand status took 151 milliseconds Oct 03 13:14:36 volumio volumio[1033]: info: sendMpdCommand status took 150 milliseconds Oct 03 13:14:36 volumio volumio[1033]: info: sendMpdCommand status took 150 milliseconds Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:36 volumio volumio[1033]: info: Command Router : Notfying DB Updatefalse Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::Close All Modals sent Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:36 volumio volumio[1033]: verbose: ControllerMpd::parseState Oct 03 13:14:36 volumio volumio[1033]: info: ControllerMpd::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: 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 03 13:14:36 volumio volumio[1033]: verbose: CURRENT POSITION 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: No code Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: ControllerMpd::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: 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 03 13:14:36 volumio volumio[1033]: verbose: CURRENT POSITION 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: No code Oct 03 13:14:36 volumio volumio[1033]: info: CoreStateMachine::pushState Oct 03 13:14:36 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Oct 03 13:14:36 volumio volumio[1033]: info: ------------------------------ 263ms Oct 03 13:14:36 volumio volumio[1033]: info: ------------------------------ 263ms Oct 03 13:14:36 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:40 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:44 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:45 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:45 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:48 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:52 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:14:56 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Oct 03 13:14:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Oct 03 13:14:56 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:15:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:15:01 volumio volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:15:01 volumio volumio[1033]: Error: connect ETIMEDOUT 31.13.70.9:80 Oct 03 13:15:01 volumio volumio[1033]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 03 13:15:01 volumio volumio[1033]: errno: -110, Oct 03 13:15:01 volumio volumio[1033]: code: 'ETIMEDOUT', Oct 03 13:15:01 volumio volumio[1033]: syscall: 'connect', Oct 03 13:15:01 volumio volumio[1033]: address: '31.13.70.9', Oct 03 13:15:01 volumio volumio[1033]: port: 80 Oct 03 13:15:01 volumio volumio[1033]: } Oct 03 13:15:01 volumio volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:15:02 volumio sudo[2417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 13:14 Oct 03 13:15:02 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"