-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-03-08 14:05:05 CST. -- Mar 08 14:05:03 volumio ntpd[766]: receive: Unexpected origin timestamp 0xeb0ef120.32a8a69e does not match aorg 0000000000.00000000 from server@114.34.171.136 xmt 0xeb765c8f.933656be Mar 08 14:05:03 volumio ntpd[766]: receive: Unexpected origin timestamp 0xeb0ef120.32a9ab1c does not match aorg 0000000000.00000000 from server@114.35.131.27 xmt 0xeb765c8f.93079cac Mar 08 14:05:03 volumio ntpd[766]: receive: Unexpected origin timestamp 0xeb0ef120.32a60188 does not match aorg 0000000000.00000000 from server@123.204.232.128 xmt 0xeb765c8f.931be4bc Mar 08 14:05:03 volumio systemd[1]: Starting Daily apt download activities... Mar 08 14:05:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 08 14:05:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Mar 08 14:05:04 volumio systemd[1]: Stopped go-librespot Daemon. Mar 08 14:05:04 volumio systemd[1]: Started go-librespot Daemon. Mar 08 14:05:04 volumio go-librespot[1526]: Librespot-go daemon starting... Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=info msg="generated new device id: 80a5028d9eb2b47185667f6a1819276c4116f7af" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="stored credentials found for 31gp7joghnfn7y3w42ppirffy5qa" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="zeroconf server listening on port 36917" Mar 08 14:05:04 volumio volumio[848]: info: Discovery: A device disappeared from network Mar 08 14:05:04 volumio volumio[848]: info: Discovery: Device volumio disappeared from network Mar 08 14:05:04 volumio volumio[848]: info: Discovery: A device disappeared from network Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="obtained new client token: AAD5f/qrgIw94XU9SrAPfssOX2KC9jyE+UoZYAieqKPjbLjM6Qgh8b6TUqUPRoncY70T7dHa+Upaz2L4bIDicmIkuPRnqDglaMR2ezYPhViKv2IR8lVAmR1qWwGLiuT4SgC4B3ItPsYrRmMf6uWtxfNSE96O8vtLrqs9SEpRPCJqrWzP134lBGYbeRoSlw4aaNWVt/W8rKFFFIShtnwL4U+DHmGSE6GkPgonUJx+hI1yzNFtdz4rieBxJSKmMrw2" Mar 08 14:05:04 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Mar 08 14:05:04 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 32. Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=info msg="connected to ap-gae2.spotify.com:4070" Mar 08 14:05:04 volumio go-librespot[1526]: time="2025-03-08T14:05:04+08:00" level=debug msg="completed keyexchange" Mar 08 14:05:04 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 08 14:05:04 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 08 14:05:04 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 08 14:05:04 volumio dnsmasq[1534]: dnsmasq: syntax check OK. Mar 08 14:05:04 volumio systemd[1536]: dnsmasq.service: Failed to execute command: Bad message Mar 08 14:05:04 volumio systemd[1536]: dnsmasq.service: Failed at step EXEC spawning /etc/init.d/dnsmasq: Bad message Mar 08 14:05:04 volumio hostapd[1535]: Configuration file: /etc/hostapd/hostapd.conf Mar 08 14:05:04 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1858: inode #1275029: comm (dnsmasq): iget: checksum invalid Mar 08 14:05:04 volumio hostapd[1535]: [93B blob data] Mar 08 14:05:04 volumio hostapd[1535]: 1 errors found in configuration file '/etc/hostapd/hostapd.conf' Mar 08 14:05:04 volumio hostapd[1535]: Failed to set up interface with /etc/hostapd/hostapd.conf Mar 08 14:05:04 volumio hostapd[1535]: Failed to initialize interface Mar 08 14:05:04 volumio systemd[1]: dnsmasq.service: Control process exited, code=exited, status=203/EXEC Mar 08 14:05:04 volumio systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Mar 08 14:05:04 volumio systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Mar 08 14:05:04 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Mar 08 14:05:04 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Mar 08 14:05:04 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 08 14:05:04 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 08 14:05:04 volumio dnsmasq[1537]: dnsmasq: syntax check OK. Mar 08 14:05:04 volumio systemd[1538]: dnsmasq.service: Failed to execute command: Bad message Mar 08 14:05:04 volumio systemd[1538]: dnsmasq.service: Failed at step EXEC spawning /etc/init.d/dnsmasq: Bad message Mar 08 14:05:04 volumio systemd[1]: dnsmasq.service: Control process exited, code=exited, status=203/EXEC Mar 08 14:05:04 volumio systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Mar 08 14:05:04 volumio systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Mar 08 14:05:04 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1858: inode #1275029: comm (dnsmasq): iget: checksum invalid Mar 08 14:05:04 volumio volumio[848]: info: Discovery: adding eea06892-5cc8-47ca-887e-d3caafe15fcf Mar 08 14:05:04 volumio volumio[848]: info: Discovery: Found device Volumio Mar 08 14:05:04 volumio volumio[848]: info: CoreCommandRouter::volumioGetState Mar 08 14:05:04 volumio volumio[848]: info: CorePlayQueue::getTrack 0 Mar 08 14:05:04 volumio volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 14:05:04 volumio volumio[848]: Error: The value NaN is not a number Mar 08 14:05:04 volumio volumio[848]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 08 14:05:04 volumio volumio[848]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 08 14:05:04 volumio volumio[848]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 08 14:05:04 volumio volumio[848]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 08 14:05:04 volumio volumio[848]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 08 14:05:04 volumio volumio[848]: at Browser.emit (events.js:315:20) Mar 08 14:05:04 volumio volumio[848]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 08 14:05:04 volumio volumio[848]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 08 14:05:04 volumio volumio[848]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 08 14:05:04 volumio volumio[848]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 08 14:05:04 volumio volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 14:05:05 volumio sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 14:04 Mar 08 14:05:05 volumio go-librespot[1526]: time="2025-03-08T14:05:05+08:00" level=debug msg="completed challenge" Mar 08 14:05:05 volumio sudo[1562]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"