-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-05-08 18:22:49 CEST. -- May 08 18:21:10 volumio2 ntpd[665]: receive: Unexpected origin timestamp 0xe9e624e2.fee7a3d7 does not match aorg 0000000000.00000000 from server@82.64.81.218 xmt 0xe9e624f6.18127638 May 08 18:21:13 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 18:21:13 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 18:21:13 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 18:21:14 volumio2 volumio-remote-updater[511]: No test mode May 08 18:21:14 volumio2 volumio-remote-updater[511]: No alpha test mode May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 08 18:21:14 volumio2 volumio[1073]: ..........................................................................................++++ May 08 18:21:14 volumio2 volumio[1073]: e is 65537 (0x010001) May 08 18:21:14 volumio2 volumio[1073]: writing RSA key May 08 18:21:14 volumio2 volumio[910]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES





NEW ADDITIONS

 

 

","title":"Update v3.661","updateavailable":true} May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: info: Retrieving Cloud Streaming UI May 08 18:21:14 volumio2 volumio[910]: info: Getting Tidal Cloud Configuration May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: info: Getting Qobuz Cloud Configuration May 08 18:21:14 volumio2 volumio[910]: info: Asking plugin for UI Config May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: info: Getting Spotify Cloud Configuration May 08 18:21:14 volumio2 volumio[910]: info: Asking plugin for UI Config May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: info: Saving Spotify Acccount May 08 18:21:14 volumio2 volumio[910]: info: Got Tidal Cloud Configuration May 08 18:21:14 volumio2 volumio[910]: info: Got it May 08 18:21:14 volumio2 volumio[910]: info: Got it May 08 18:21:14 volumio2 volumio[910]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 08 18:21:14 volumio2 volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 18:21:14 volumio2 volumio[910]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetBrowseSources May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:15 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 18:21:15 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:19 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:21 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:23 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:26 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:27 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:28 volumio2 volumio[910]: error: MyVolumio Plugins failed to start in a timely fashion May 08 18:21:28 volumio2 volumio[910]: info: BOOT COMPLETED May 08 18:21:28 volumio2 volumio[910]: [Metrics] CommandRouter: 62s 226.43ms May 08 18:21:28 volumio2 volumio[910]: info: CoreCommandRouter::volumiosetStartupVolume May 08 18:21:28 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 18:21:28 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 18:21:28 volumio2 volumio[910]: info: CoreCommandRouter::Close All Modals sent May 08 18:21:28 volumio2 volumio[910]: info: CoreCommandRouter::Close All Modals sent May 08 18:21:29 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 08 18:21:29 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 18:21:29 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 08 18:21:31 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:32 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:35 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:37 volumio2 volumio[910]: error: error May 08 18:21:38 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:39 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:43 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 18:21:43 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:44 volumio2 volumio[910]: info: UPDATER: Scheduling automatic update May 08 18:21:44 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime May 08 18:21:44 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime May 08 18:21:44 volumio2 volumio[910]: info: UPDATER: Auto update will take place at: Thu May 09 2024 05:04:50 GMT+0200 (Central European Summer Time) May 08 18:21:48 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetState May 08 18:21:48 volumio2 volumio[910]: info: CorePlayQueue::getTrack 0 May 08 18:21:49 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:21:49 volumio2 volumio[910]: info: CoreCommandRouter::volumioGetQueue May 08 18:21:49 volumio2 volumio[910]: info: CoreStateMachine::getQueue May 08 18:21:49 volumio2 volumio[910]: info: CorePlayQueue::getQueue May 08 18:21:54 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 08 18:21:54 volumio2 volumio[910]: info: Preload queue cleared May 08 18:21:54 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:00 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:04 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 08 18:22:04 volumio2 volumio[910]: info: Preload queue cleared May 08 18:22:06 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:11 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:14 volumio2 volumio[910]: Searching plugin music_service/webradio May 08 18:22:14 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 18:22:14 volumio2 volumio[910]: (node:910) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found May 08 18:22:14 volumio2 volumio[910]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) May 08 18:22:14 volumio2 volumio[910]: at /volumio/app/plugins/music_service/webradio/index.js:962:31 May 08 18:22:14 volumio2 volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 08 18:22:14 volumio2 volumio[910]: (Use `node --trace-warnings ...` to show where the warning was created) May 08 18:22:14 volumio2 volumio[910]: (node:910) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) May 08 18:22:14 volumio2 volumio[910]: (node:910) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. May 08 18:22:16 volumio2 volumio[910]: Searching plugin music_service/webradio May 08 18:22:16 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: webradio , search May 08 18:22:16 volumio2 volumio[910]: info: All search sources collected, pushing search results May 08 18:22:17 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:22 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:28 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:29 volumio2 volumio[910]: error: error May 08 18:22:34 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:39 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:45 volumio2 wpa_supplicant[746]: wlan0: Failed to initiate sched scan May 08 18:22:48 volumio2 volumio[910]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch May 08 18:22:48 volumio2 volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:22:48 volumio2 volumio[910]: TypeError: Cannot read property 'then' of undefined May 08 18:22:48 volumio2 volumio[910]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:470:20) May 08 18:22:48 volumio2 volumio[910]: at Socket.emit (events.js:315:20) May 08 18:22:48 volumio2 volumio[910]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 08 18:22:48 volumio2 volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 08 18:22:48 volumio2 volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 18:22:49 volumio2 sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 18:21 May 08 18:22:49 volumio2 sudo[1221]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"