-- Logs begin at Sun 2024-11-17 11:46:18 UTC, end at Fri 2024-11-29 11:03:11 UTC. -- Nov 29 11:02:12 volumio systemd[1]: Time has been changed Nov 29 11:02:13 volumio dhcpd[977]: Wrote 3 leases to leases file. Nov 29 11:02:15 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 11:02:15 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 11:02:16 volumio volumio[960]: info: mDNS: A device disapperared from network Nov 29 11:02:16 volumio volumio[960]: info: mDNS: Device volumio disapperared from network Nov 29 11:02:16 volumio volumio[960]: info: mDNS: A device disapperared from network Nov 29 11:02:16 volumio volumio[960]: Discovery: adding 4e36fd61-553f-4f13-9996-407abb51a2d3 Nov 29 11:02:16 volumio volumio[960]: info: mDNS: Found device Volumio Nov 29 11:02:16 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Nov 29 11:02:16 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:16 volumio volumio[960]: Discovery: this is already registered, 4e36fd61-553f-4f13-9996-407abb51a2d3 Nov 29 11:02:16 volumio volumio[960]: info: mDNS: Found device Volumio Nov 29 11:02:16 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Nov 29 11:02:16 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:16 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Nov 29 11:02:16 volumio volumio[960]: info: CoreStateMachine::getQueue Nov 29 11:02:16 volumio volumio[960]: info: CorePlayQueue::getQueue Nov 29 11:02:16 volumio volumio[960]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=3ae82554a897d65ced7df8529eb1bd69" http://updates.volumio.org:7070/downloader-v1/track-device Nov 29 11:02:16 volumio volumio[960]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 29 11:02:16 volumio volumio[960]: Dload Upload Total Spent Left Speed Nov 29 11:02:16 volumio volumio[960]: [1.3K blob data] Nov 29 11:02:16 volumio volumio[960]: retrying in 5 seconds, trial 0 Nov 29 11:02:16 volumio volumio[960]: Volumio Calling Home Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Melanie%20C/573f6bf0-88e8-4fde-a435-e9b95fcac709.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Leann%20Rimes/b376a5c4-5561-4ae4-8e46-cae5ad0fabc4.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Kelly%20Rowland/e00fa690-b43d-4793-a2f5-d6ad72c05394.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bianca%20Ryan/9abe7c57-fbcd-48b1-ae33-3f651c0d1eea.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bandari/c752b0b0-bd5f-4d24-a8e7-62200c54d4de.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joy%20Enriquez/24ad4436-5558-4c3c-be39-5dd9ac89f4b1.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Club%208/2356d46a-c6aa-46ea-8e2c-5f30f34e6f97.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Delta%20Goodrem/b6182324-966a-475e-8a45-bcace9596654.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sandy%20Lam/3c89412b-13b4-439e-ac28-ee9cdcade89c.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Roxette/4775e5e6-9c0d-41a4-a73a-4977adb3bd86.jpg' Nov 29 11:02:16 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/2d17e4d2-49be-4d9a-92c8-61449785fe88.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/5d795a10-8784-4b9d-88b3-5c3d2240bf4c.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E4%BF%8A%E6%9D%B0/8aa11ba4-c945-4315-91c8-c5767e970ab2.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F%3F/2349594e-71b5-4bba-9e23-00f64c8ad2d3.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Brian%20McKnight/d35dfca4-b0fc-46af-8189-f45b3ad3ee6c.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dominica/b123ff9a-99f2-4195-87f1-3a65f4a80129.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Eagles/c29f4d75-aa48-4080-93ec-b44c118ce5da.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Celine%20Dion/a0157495-7973-4abc-b76e-bdd3ff4b949b.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/James%20Last/4693b46a-f559-4c0e-ac79-58cb827089f3.jpg' Nov 29 11:02:17 volumio volumio[960]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Bolton/897a97e3-e3af-4bf3-a377-3eede5993633.jpg' Nov 29 11:02:27 volumio volumio[960]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=3ae82554a897d65ced7df8529eb1bd69" http://updates.volumio.org:7070/downloader-v1/track-device Nov 29 11:02:27 volumio volumio[960]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 29 11:02:27 volumio volumio[960]: Dload Upload Total Spent Left Speed Nov 29 11:02:27 volumio volumio[960]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Nov 29 11:02:27 volumio volumio[960]: retrying in 5 seconds, trial 1 Nov 29 11:02:27 volumio volumio[960]: Volumio Calling Home Nov 29 11:02:29 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 11:02:34 volumio volumio[960]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 11:02:34 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 11:02:37 volumio volumio[960]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=3ae82554a897d65ced7df8529eb1bd69" http://updates.volumio.org:7070/downloader-v1/track-device Nov 29 11:02:37 volumio volumio[960]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 29 11:02:37 volumio volumio[960]: Dload Upload Total Spent Left Speed Nov 29 11:02:37 volumio volumio[960]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Nov 29 11:02:37 volumio volumio[960]: retrying in 5 seconds, trial 2 Nov 29 11:02:37 volumio volumio[960]: Volumio Calling Home Nov 29 11:02:38 volumio sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 29 11:02:38 volumio sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:02:38 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 29 11:02:38 volumio sudo[1327]: pam_unix(sudo:session): session closed for user root Nov 29 11:02:38 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 11:02:38 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Nov 29 11:02:38 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::volumioGetQueue Nov 29 11:02:38 volumio volumio[960]: info: CoreStateMachine::getQueue Nov 29 11:02:38 volumio volumio[960]: info: CorePlayQueue::getQueue Nov 29 11:02:38 volumio volumio[960]: info: Listing playlists Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 29 11:02:38 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 11:02:42 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:02:42 volumio volumio[960]: info: CURURI: music-library Nov 29 11:02:42 volumio volumio[960]: error: Failed LSINFO: null Nov 29 11:02:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:02:55 volumio volumio[960]: info: CURURI: music-library Nov 29 11:02:55 volumio volumio[960]: error: Failed LSINFO: null Nov 29 11:02:55 volumio kernel: usb 2-1: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd Nov 29 11:02:55 volumio kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0567, bcdDevice= 3.24 Nov 29 11:02:55 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 29 11:02:55 volumio kernel: usb 2-1: Product: USB to ATA/ATAPI Bridge Nov 29 11:02:55 volumio kernel: usb 2-1: Manufacturer: JMicron Nov 29 11:02:55 volumio kernel: usb 2-1: SerialNumber: 0123456789ABCDEF Nov 29 11:02:55 volumio kernel: usb-storage 2-1:1.0: USB Mass Storage device detected Nov 29 11:02:55 volumio kernel: usb-storage 2-1:1.0: Quirks match for vid 152d pid 0567: 5000000 Nov 29 11:02:55 volumio kernel: scsi host0: usb-storage 2-1:1.0 Nov 29 11:02:56 volumio kernel: scsi 0:0:0:0: Direct-Access JMicron Generic DISK00 0103 PQ: 0 ANSI: 6 Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB) Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 2b 00 00 00 Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Nov 29 11:02:56 volumio kernel: sda: sda1 Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Nov 29 11:02:56 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 29 11:02:56 volumio udisks-glue[600]: Device file /dev/sda inserted Nov 29 11:02:57 volumio udisks-glue[600]: Device file /dev/sda1 inserted Nov 29 11:02:57 volumio udisks-glue[600]: Trying to automount /dev/sda1... Nov 29 11:02:57 volumio ntfs-3g[1403]: Version 2014.2.15AR.2 integrated FUSE 28 Nov 29 11:02:57 volumio ntfs-3g[1403]: Mounted /dev/sda1 (Read-Write, label "新加卷", NTFS 3.1) Nov 29 11:02:57 volumio ntfs-3g[1403]: Cmdline options: rw,nodev,nosuid,noatime,uid=1000,gid=1000,dmask=0077,fmask=0177,dmask=0000,fmask=0000,uhelper=udisks Nov 29 11:02:57 volumio ntfs-3g[1403]: Mount options: rw,nodev,nosuid,uhelper=udisks,allow_other,nonempty,noatime,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 29 11:02:57 volumio ntfs-3g[1403]: Global ownership and permissions enforced, configuration type 7 Nov 29 11:02:57 volumio udisks-glue[600]: Successfully automounted /dev/sda1 at /media/??? Nov 29 11:02:57 volumio udisks-glue[600]: Device file /dev/sda1 mounted at /media/??? Nov 29 11:02:57 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Nov 29 11:02:57 volumio volumio[960]: info: Nov 29 11:02:57 volumio volumio[960]: ---------------------------- MPD announces state update: update Nov 29 11:02:57 volumio udisks-glue[600]: Updating DB (#2) ... Nov 29 11:02:57 volumio udisks-glue[600]: volume:100% repeat: off random: off single: off consume: off Nov 29 11:02:57 volumio volumio[960]: info: ControllerMpd::getState Nov 29 11:02:57 volumio volumio[960]: verbose: ControllerMpd::sendMpdCommand status Nov 29 11:02:57 volumio volumio[960]: verbose: ControllerMpd::parseState Nov 29 11:02:57 volumio volumio[960]: info: Command Router : Notfying DB Updatetrue Nov 29 11:02:57 volumio volumio[960]: verbose: ControllerMpd::parseState Nov 29 11:02:57 volumio volumio[960]: info: ControllerMpd::pushState Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::servicePushState Nov 29 11:02:57 volumio volumio[960]: info: CoreStateMachine::pushState Nov 29 11:02:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 11:02:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:57 volumio volumio[960]: 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} Nov 29 11:02:57 volumio volumio[960]: verbose: CURRENT POSITION 0 Nov 29 11:02:57 volumio volumio[960]: info: CoreStateMachine::syncState stateService stop Nov 29 11:02:57 volumio volumio[960]: info: CoreStateMachine::syncState currentStatus stop Nov 29 11:02:57 volumio volumio[960]: info: CoreStateMachine::pushState Nov 29 11:02:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 11:02:57 volumio volumio[960]: info: No code Nov 29 11:02:57 volumio volumio[960]: info: CoreStateMachine::pushState Nov 29 11:02:57 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Nov 29 11:02:57 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 11:02:57 volumio volumio[960]: info: ------------------------------ 129ms Nov 29 11:02:59 volumio volumio[960]: info: CoreCommandRouter::Close All Modals sent Nov 29 11:03:00 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:03:00 volumio volumio[960]: info: CURURI: music-library Nov 29 11:03:00 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:03:00 volumio volumio[960]: info: CURURI: music-library/USB Nov 29 11:03:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:03:01 volumio volumio[960]: info: CURURI: music-library/USB/新加卷 Nov 29 11:03:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 29 11:03:04 volumio volumio[960]: info: CURURI: music-library/USB/新加卷/中文歌曲 Nov 29 11:03:10 volumio volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 11:03:10 volumio volumio[960]: { Error: connect ETIMEDOUT 38.121.72.166:80 Nov 29 11:03:10 volumio volumio[960]: at Object._errnoException (util.js:1022:11) Nov 29 11:03:10 volumio volumio[960]: at _exceptionWithHostPort (util.js:1044:20) Nov 29 11:03:10 volumio volumio[960]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 29 11:03:10 volumio volumio[960]: code: 'ETIMEDOUT', Nov 29 11:03:10 volumio volumio[960]: errno: 'ETIMEDOUT', Nov 29 11:03:10 volumio volumio[960]: syscall: 'connect', Nov 29 11:03:10 volumio volumio[960]: address: '38.121.72.166', Nov 29 11:03:10 volumio volumio[960]: port: 80 } Nov 29 11:03:10 volumio volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 11:03:11 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 11:02 Nov 29 11:03:11 volumio sudo[1444]: 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"