-- Logs begin at Tue 2024-09-24 21:49:37 CEST, end at Sat 2024-10-19 09:34:55 CEST. -- Oct 19 09:34:33 volumio ntpd[843]: receive: Unexpected origin timestamp 0xea9d96a7.964feefb does not match aorg 0000000000.00000000 from server@213.192.54.232 xmt 0xeabddf89.c462511a Oct 19 09:34:33 volumio ntpd[843]: receive: Unexpected origin timestamp 0xea9d96a7.9647eb45 does not match aorg 0000000000.00000000 from server@78.108.96.197 xmt 0xeabddf89.c481f2af Oct 19 09:34:33 volumio ntpd[843]: receive: Unexpected origin timestamp 0xea9d96a7.96443078 does not match aorg 0000000000.00000000 from server@78.108.102.237 xmt 0xeabddf89.c477283b Oct 19 09:34:33 volumio ntpd[843]: receive: Unexpected origin timestamp 0xea9d96a7.964b2917 does not match aorg 0000000000.00000000 from server@81.25.28.124 xmt 0xeabddf89.c4beb612 Oct 19 09:34:33 volumio systemd[1]: Starting Daily apt download activities... Oct 19 09:34:33 volumio volumio[1239]: ............................................................................................++++ Oct 19 09:34:33 volumio volumio[1239]: e is 65537 (0x010001) Oct 19 09:34:33 volumio volumio[1239]: writing RSA key Oct 19 09:34:35 volumio systemd[1]: apt-daily.service: Succeeded. Oct 19 09:34:35 volumio systemd[1]: Started Daily apt download activities. Oct 19 09:34:35 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 19 09:34:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 19 09:34:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 09:34:38 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 19 09:34:41 volumio volumio[1031]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 19 09:34:41 volumio volumio[1031]: info: Completed starting MyVolumio Plugin Oct 19 09:34:41 volumio volumio[1031]: info: BOOT COMPLETED Oct 19 09:34:41 volumio volumio[1031]: [Metrics] CommandRouter: 57s 346.37ms Oct 19 09:34:41 volumio volumio[1031]: info: CoreCommandRouter::volumiosetStartupVolume Oct 19 09:34:41 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 09:34:41 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 09:34:41 volumio volumio[1031]: info: CoreCommandRouter::Close All Modals sent Oct 19 09:34:41 volumio volumio[1031]: info: CoreCommandRouter::Close All Modals sent Oct 19 09:34:41 volumio volumio[1031]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 19 09:34:41 volumio volumio[1031]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 19 09:34:41 volumio volumio[1031]: aplay: main:828: audio open error: No such device Oct 19 09:34:42 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 19 09:34:42 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 09:34:42 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 19 09:34:42 volumio volumio[1031]: info: MyVolumio login type: Token Oct 19 09:34:42 volumio volumio[1031]: error: MyVolumio Custom Token format not valid, refreshing it Oct 19 09:34:43 volumio volumio[1031]: info: MyVolumio login type: Token Oct 19 09:34:44 volumio volumio[1031]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 19 09:34:49 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 09:34:49 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 09:34:49 volumio volumio[1031]: info: Discovery: Getting this device information Oct 19 09:34:49 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Oct 19 09:34:49 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 09:34:50 volumio volumio[1031]: info: MyVolumio token set successfully Oct 19 09:34:50 volumio volumio[1031]: info: MYVOLUMIO: Adding device Oct 19 09:34:50 volumio volumio[1031]: info: MYVOLUMIO: Evaluating Server Oct 19 09:34:52 volumio volumio[1031]: info: MyVolumio status changed Oct 19 09:34:52 volumio volumio[1031]: info: Streaming services startup Oct 19 09:34:52 volumio volumio[1031]: info: Starting Streaming Daemon Oct 19 09:34:52 volumio volumio[1031]: info: Removing browser output: myVolumio user plan is not superstar Oct 19 09:34:52 volumio volumio[1031]: info: Removing audio output: Oct 19 09:34:52 volumio volumio[1031]: info: Stoppping Tunnel 1 Oct 19 09:34:52 volumio sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 19 09:34:52 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio volumio[1031]: info: Retrieving Cloud Streaming UI Oct 19 09:34:52 volumio sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 19 09:34:52 volumio sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 09:34:52 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root Oct 19 09:34:52 volumio volumio[1031]: error: Cannot start Volumio Streaming Daemon Oct 19 09:34:52 volumio volumio[1031]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 19 09:34:52 volumio volumio[1031]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 19 09:34:52 volumio volumio[1031]: info: Getting Tidal Cloud Configuration Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio volumio[1031]: info: Getting Qobuz Cloud Configuration Oct 19 09:34:52 volumio volumio[1031]: info: Asking plugin for UI Config Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio volumio[1031]: info: Getting Spotify Cloud Configuration Oct 19 09:34:52 volumio volumio[1031]: info: Asking plugin for UI Config Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio volumio[1031]: info: Saving Spotify Acccount Oct 19 09:34:52 volumio volumio[1031]: info: Got Tidal Cloud Configuration Oct 19 09:34:52 volumio volumio[1031]: info: Got it Oct 19 09:34:52 volumio volumio[1031]: info: Got it Oct 19 09:34:52 volumio volumio[1031]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 09:34:52 volumio sudo[1517]: pam_unix(sudo:session): session closed for user root Oct 19 09:34:52 volumio volumio[1031]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 19 09:34:52 volumio volumio[1031]: info: Remote SSH Stopped Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 09:34:52 volumio volumio[1031]: info: Setting Geolocation for MyVolumio to eu7 Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 09:34:52 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 09:34:53 volumio volumio[1031]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 19 09:34:53 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 09:34:53 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 19 09:34:53 volumio volumio[1031]: info: Updating MyVolumio device info Oct 19 09:34:53 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 09:34:54 volumio volumio[1031]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 19 09:34:54 volumio volumio[1031]: info: Discovery: A device disappeared from network Oct 19 09:34:54 volumio volumio[1031]: info: Discovery: Device volumio disappeared from network Oct 19 09:34:54 volumio volumio[1031]: info: Discovery: adding 4dbfa54e-d91c-4a67-923f-bd8522ec126c Oct 19 09:34:54 volumio volumio[1031]: info: Discovery: Found device Volumio Oct 19 09:34:54 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Oct 19 09:34:54 volumio volumio[1031]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 09:34:54 volumio volumio[1031]: Error: The value NaN is not a number Oct 19 09:34:54 volumio volumio[1031]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 19 09:34:54 volumio volumio[1031]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 19 09:34:54 volumio volumio[1031]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 19 09:34:54 volumio volumio[1031]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 19 09:34:54 volumio volumio[1031]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 19 09:34:54 volumio volumio[1031]: at Browser.emit (events.js:315:20) Oct 19 09:34:54 volumio volumio[1031]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 19 09:34:54 volumio volumio[1031]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 19 09:34:54 volumio volumio[1031]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 19 09:34:54 volumio volumio[1031]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 19 09:34:54 volumio volumio[1031]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 19 09:34:54 volumio volumio[1031]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 19 09:34:54 volumio volumio[1031]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 19 09:34:54 volumio volumio[1031]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 09:34:55 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 09:33 Oct 19 09:34:55 volumio sudo[1531]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"