-- Logs begin at Sun 2025-04-13 10:13:53 CEST, end at Mon 2025-04-14 18:17:36 CEST. -- Apr 14 18:17:05 volumiox ntpd[805]: receive: Unexpected origin timestamp 0xeba5f114.04275e19 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xeba7b381.b988ecd6 Apr 14 18:17:05 volumiox ntpd[805]: receive: Unexpected origin timestamp 0xeba5f114.04236008 does not match aorg 0000000000.00000000 from server@37.247.53.178 xmt 0xeba7b381.b9a746d6 Apr 14 18:17:05 volumiox ntpd[805]: receive: Unexpected origin timestamp 0xeba5f114.04141f1b does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xeba7b381.bbd8e5dd Apr 14 18:17:05 volumiox systemd[1]: Starting Daily apt download activities... Apr 14 18:17:05 volumiox systemd[1]: Starting Daily man-db regeneration... Apr 14 18:17:05 volumiox volumio[1002]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 18:17:05 volumiox volumio[1002]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 18:17:06 volumiox systemd[1]: man-db.service: Succeeded. Apr 14 18:17:06 volumiox systemd[1]: Started Daily man-db regeneration. Apr 14 18:17:06 volumiox volumio[1002]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/2tera Apr 14 18:17:08 volumiox volumio[1002]: info: Scanning removed location : "USB/2tera" Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:08 volumiox volumio[1002]: info: Apr 14 18:17:08 volumiox volumio[1002]: ---------------------------- MPD announces state update: update Apr 14 18:17:08 volumiox volumio[1002]: info: ControllerMpd::getState Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:08 volumiox volumio[1002]: info: sendMpdCommand status took 10 milliseconds Apr 14 18:17:08 volumiox volumio[1002]: info: sendMpdCommand status took 7 milliseconds Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:08 volumiox volumio[1002]: info: Command Router : Notfying DB Updatetrue Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:08 volumiox volumio[1002]: info: ControllerMpd::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::servicePushState Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: 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} Apr 14 18:17:08 volumiox volumio[1002]: verbose: CURRENT POSITION 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::syncState stateService stop Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: No code Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: ------------------------------ 601ms Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:08 volumiox volumio[1002]: info: Apr 14 18:17:08 volumiox volumio[1002]: ---------------------------- MPD announces state update: update Apr 14 18:17:08 volumiox volumio[1002]: info: ControllerMpd::getState Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:08 volumiox volumio[1002]: info: sendMpdCommand status took 98 milliseconds Apr 14 18:17:08 volumiox volumio[1002]: info: sendMpdCommand status took 97 milliseconds Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:08 volumiox volumio[1002]: info: Command Router : Notfying DB Updatefalse Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::Close All Modals sent Apr 14 18:17:08 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:08 volumiox volumio[1002]: info: ControllerMpd::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::servicePushState Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: 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} Apr 14 18:17:08 volumiox volumio[1002]: verbose: CURRENT POSITION 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::syncState stateService stop Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: No code Apr 14 18:17:08 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:08 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:08 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:08 volumiox volumio[1002]: info: ------------------------------ 304ms Apr 14 18:17:09 volumiox systemd[1]: apt-daily.service: Succeeded. Apr 14 18:17:09 volumiox systemd[1]: Started Daily apt download activities. Apr 14 18:17:09 volumiox systemd[1]: Starting Daily apt upgrade and clean activities... Apr 14 18:17:09 volumiox volumio[1002]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 18:17:11 volumiox systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 14 18:17:11 volumiox systemd[1]: Started Daily apt upgrade and clean activities. Apr 14 18:17:13 volumiox volumio[1002]: info: Discovery: A device disappeared from network Apr 14 18:17:13 volumiox volumio[1002]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 14 18:17:13 volumiox volumio[1002]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 14 18:17:14 volumiox kernel: usb 1-1.3: new high-speed USB device number 5 using dwc_otg Apr 14 18:17:14 volumiox kernel: usb 1-1.3: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00 Apr 14 18:17:14 volumiox kernel: usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Apr 14 18:17:14 volumiox kernel: usb 1-1.3: Product: ASMT1153E Apr 14 18:17:14 volumiox kernel: usb 1-1.3: Manufacturer: asmedia Apr 14 18:17:14 volumiox kernel: usb 1-1.3: SerialNumber: 00000001234567A0 Apr 14 18:17:14 volumiox kernel: usb 1-1.3: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is Apr 14 18:17:14 volumiox kernel: usb 1-1.3: required by the UAS driver. Please try an other USB controller if you wish to use UAS. Apr 14 18:17:14 volumiox kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Apr 14 18:17:14 volumiox kernel: usb-storage 1-1.3:1.0: Quirks match for vid 174c pid 55aa: 400000 Apr 14 18:17:14 volumiox kernel: scsi host0: usb-storage 1-1.3:1.0 Apr 14 18:17:15 volumiox kernel: scsi 0:0:0:0: Direct-Access ASMT 2115 0 PQ: 0 ANSI: 6 Apr 14 18:17:15 volumiox kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 14 18:17:15 volumiox kernel: sd 0:0:0:0: [sda] Spinning up disk... Apr 14 18:17:21 volumiox kernel: ......ready Apr 14 18:17:21 volumiox kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) Apr 14 18:17:21 volumiox kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Apr 14 18:17:21 volumiox kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 14 18:17:21 volumiox kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Apr 14 18:17:21 volumiox kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 14 18:17:22 volumiox kernel: sda: sda2 Apr 14 18:17:22 volumiox kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 14 18:17:22 volumiox volumio[1002]: info: Mounting Device 2tera Apr 14 18:17:22 volumiox sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/2tera -o noatime Apr 14 18:17:22 volumiox sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 18:17:22 volumiox mount.exfat[2109]: volume was not unmounted cleanly Apr 14 18:17:22 volumiox sudo[2107]: pam_unix(sudo:session): session closed for user root Apr 14 18:17:22 volumiox volumio[1002]: WARN: volume was not unmounted cleanly. Apr 14 18:17:22 volumiox volumio[1002]: info: Scanning new location : "USB/2tera" Apr 14 18:17:22 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:22 volumiox volumio[1002]: info: Apr 14 18:17:22 volumiox volumio[1002]: ---------------------------- MPD announces state update: update Apr 14 18:17:22 volumiox volumio[1002]: info: ControllerMpd::getState Apr 14 18:17:22 volumiox volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Apr 14 18:17:22 volumiox volumio[1002]: info: sendMpdCommand status took 7 milliseconds Apr 14 18:17:22 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:22 volumiox volumio[1002]: info: Command Router : Notfying DB Updatetrue Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::Close All Modals sent Apr 14 18:17:22 volumiox volumio[1002]: info: sendMpdCommand status took 160 milliseconds Apr 14 18:17:22 volumiox volumio[1002]: verbose: ControllerMpd::parseState Apr 14 18:17:22 volumiox volumio[1002]: info: ControllerMpd::pushState Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::servicePushState Apr 14 18:17:22 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:22 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:22 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:22 volumiox volumio[1002]: 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} Apr 14 18:17:22 volumiox volumio[1002]: verbose: CURRENT POSITION 0 Apr 14 18:17:22 volumiox volumio[1002]: info: CoreStateMachine::syncState stateService stop Apr 14 18:17:22 volumiox volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Apr 14 18:17:22 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:22 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:22 volumiox volumio[1002]: info: No code Apr 14 18:17:22 volumiox volumio[1002]: info: CoreStateMachine::pushState Apr 14 18:17:22 volumiox volumio[1002]: info: CorePlayQueue::getTrack 0 Apr 14 18:17:22 volumiox volumio[1002]: info: CoreCommandRouter::volumioPushState Apr 14 18:17:23 volumiox volumio[1002]: info: ------------------------------ 220ms Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: Retrieving Cloud Streaming UI Apr 14 18:17:26 volumiox volumio[1002]: info: Getting Tidal Cloud Configuration Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: Getting Qobuz Cloud Configuration Apr 14 18:17:26 volumiox volumio[1002]: info: Asking plugin for UI Config Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: Getting Spotify Cloud Configuration Apr 14 18:17:26 volumiox volumio[1002]: info: Asking plugin for UI Config Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: Saving Spotify Acccount Apr 14 18:17:26 volumiox volumio[1002]: info: Got it Apr 14 18:17:26 volumiox volumio[1002]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 14 18:17:26 volumiox volumio[1002]: info: Got Tidal Cloud Configuration Apr 14 18:17:26 volumiox volumio[1002]: info: Got it Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 18:17:26 volumiox volumio[1002]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 18:17:27 volumiox mount.exfat[2110]: '01 I Feel You.lrc' is empty but marked as contiguous (0x20) Apr 14 18:17:27 volumiox mount.exfat[2110]: '01 I Feel You.lrc' is empty but marked as contiguous (0x20) Apr 14 18:17:27 volumiox mount.exfat[2110]: failed to open directory '/DOWNLOADS/Depeche Mode/The Singles 86-98 (Album)/Disc 02' Apr 14 18:17:28 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 18:17:29 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 14 18:17:32 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 14 18:17:32 volumiox volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 14 18:17:33 volumiox volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:17:33 volumiox volumio[1002]: Error: getaddrinfo -3007 Apr 14 18:17:33 volumiox volumio[1002]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 14 18:17:33 volumiox volumio[1002]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 14 18:17:33 volumiox volumio[1002]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 14 18:17:33 volumiox volumio[1002]: code: -3007, Apr 14 18:17:33 volumiox volumio[1002]: errno: -3007, Apr 14 18:17:33 volumiox volumio[1002]: syscall: 'getaddrinfo' Apr 14 18:17:33 volumiox volumio[1002]: } Apr 14 18:17:33 volumiox volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 18:17:36 volumiox sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-14 18:16 Apr 14 18:17:36 volumiox sudo[2134]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"