-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sun 2024-04-21 22:50:07 EDT. -- Apr 21 22:49:00 volumio volumio[971]: info: Setting Geolocation for MyVolumio to us3 Apr 21 22:49:00 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:00 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:00 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:00 volumio volumio[971]: info: Successfully Added MyVolumio device Apr 21 22:49:01 volumio volumio[971]: info: Updating MyVolumio device info Apr 21 22:49:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:01 volumio volumio[971]: info: Successfully Updated MyVolumio device Apr 21 22:49:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 22:49:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 22:49:04 volumio volumio-remote-updater[525]: Test mode enabled Apr 21 22:49:04 volumio volumio-remote-updater[525]: No alpha test mode Apr 21 22:49:04 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 21 22:49:04 volumio volumio[971]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 21 22:49:04 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 21 22:49:04 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 22:49:04 volumio volumio[971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 95 Apr 21 22:49:04 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Apr 21 22:49:04 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Apr 21 22:49:04 volumio ntpd[645]: Soliciting pool server 204.93.207.12 Apr 21 22:49:04 volumio ntpd[645]: Soliciting pool server 137.190.2.4 Apr 21 22:49:04 volumio ntpd[645]: Soliciting pool server 167.248.62.201 Apr 21 22:49:04 volumio ntpd[645]: Soliciting pool server 217.180.209.214 Apr 21 22:49:05 volumio ntpd[645]: Soliciting pool server 193.187.181.6 Apr 21 22:49:05 volumio ntpd[645]: Soliciting pool server 198.137.202.56 Apr 21 22:49:05 volumio ntpd[645]: Soliciting pool server 66.85.78.80 Apr 21 22:49:05 volumio ntpd[645]: Soliciting pool server 147.182.158.78 Apr 21 22:49:06 volumio ntpd[645]: Soliciting pool server 50.205.57.38 Apr 21 22:49:06 volumio ntpd[645]: Soliciting pool server 104.156.246.53 Apr 21 22:49:06 volumio ntpd[645]: Soliciting pool server 45.55.126.202 Apr 21 22:49:06 volumio ntpd[645]: Soliciting pool server 65.100.46.166 Apr 21 22:49:07 volumio ntpd[645]: Soliciting pool server 64.79.100.196 Apr 21 22:49:07 volumio ntpd[645]: Soliciting pool server 5.78.89.3 Apr 21 22:49:07 volumio ntpd[645]: Soliciting pool server 64.6.144.6 Apr 21 22:49:07 volumio ntpd[645]: Soliciting pool server 71.19.144.140 Apr 21 22:49:08 volumio ntpd[645]: Soliciting pool server 2600:3c02::f03c:91ff:fe84:3cb5 Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 22:49:09 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c706f13a does not match aorg 0000000000.00000000 from server@45.55.126.202 xmt 0xe9d04eba.d3e95b48 Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c70d458c does not match aorg 0000000000.00000000 from server@66.85.78.80 xmt 0xe9d04eba.d747af79 Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c7023fb7 does not match aorg 0000000000.00000000 from server@104.156.246.53 xmt 0xe9d04eba.d6292d14 Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c6f180f5 does not match aorg 0000000000.00000000 from server@71.19.144.140 xmt 0xe9d04eba.d582b22f Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c70a334c does not match aorg 0000000000.00000000 from server@193.187.181.6 xmt 0xe9d04eba.d6c52ad9 Apr 21 22:49:30 volumio ntpd[645]: receive: Unexpected origin timestamp 0xe9d04ea7.c6feefea does not match aorg 0000000000.00000000 from server@65.100.46.166 xmt 0xe9d04eba.d7fa3530 Apr 21 22:49:30 volumio volumio[971]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 21 22:49:30 volumio volumio[971]: info: Completed starting MyVolumio Plugin Apr 21 22:49:31 volumio volumio[971]: info: BOOT COMPLETED Apr 21 22:49:31 volumio volumio[971]: [Metrics] CommandRouter: 63s 248.91ms Apr 21 22:49:31 volumio volumio[971]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 22:49:31 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:49:31 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:49:31 volumio volumio[971]: info: CoreCommandRouter::Close All Modals sent Apr 21 22:49:31 volumio volumio[971]: info: CoreCommandRouter::Close All Modals sent Apr 21 22:49:32 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 22:49:32 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 22:49:32 volumio volumio[971]: Home directory not accessible: Permission denied Apr 21 22:49:32 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 22:49:32 volumio volumio[1094]: ..................................................................................................................................................++++ Apr 21 22:49:35 volumio volumio[1094]: .........++++ Apr 21 22:49:35 volumio volumio[1094]: e is 65537 (0x010001) Apr 21 22:49:35 volumio volumio[1094]: writing RSA key Apr 21 22:49:57 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 22:49:57 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 21 22:50:06 volumio volumio[971]: Uploading: contemporary_advanced.zip Apr 21 22:50:06 volumio volumio[971]: Created safe filename as '7d976764-efb6-413f-9d1e-181795461ef2.zip' Apr 21 22:50:06 volumio volumio[971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 96 Apr 21 22:50:06 volumio volumio[971]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/7d976764-efb6-413f-9d1e-181795461ef2.zip Apr 21 22:50:06 volumio volumio[971]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/7d976764-efb6-413f-9d1e-181795461ef2.zip Apr 21 22:50:06 volumio volumio[971]: info: Folder /tmp/plugins removed Apr 21 22:50:06 volumio volumio[971]: info: An error occurred installing the plugin. Rolling back config Apr 21 22:50:06 volumio volumio[971]: info: Plugin folders cleanup Apr 21 22:50:06 volumio volumio[971]: info: Scanning into folder /volumio/app/plugins/ Apr 21 22:50:06 volumio volumio[971]: info: Scanning category audio_interface Apr 21 22:50:06 volumio volumio[971]: info: Scanning category miscellanea Apr 21 22:50:06 volumio volumio[971]: info: Scanning category music_service Apr 21 22:50:06 volumio volumio[971]: info: Scanning category plugins.json Apr 21 22:50:06 volumio volumio[971]: info: Scanning category system_controller Apr 21 22:50:06 volumio volumio[971]: info: Scanning category user_interface Apr 21 22:50:06 volumio volumio[971]: info: Scanning into folder /data/plugins/ Apr 21 22:50:06 volumio volumio[971]: info: Scanning category music_service Apr 21 22:50:06 volumio volumio[971]: info: Scanning category system_controller Apr 21 22:50:06 volumio volumio[971]: info: Scanning category system_hardware Apr 21 22:50:06 volumio volumio[971]: info: Scanning category user_interface Apr 21 22:50:06 volumio volumio[971]: info: Plugin folders cleanup completed Apr 21 22:50:06 volumio volumio[971]: info: Error: Error Apr 21 22:50:06 volumio volumio[971]: info: Folder /tmp/plugins removed Apr 21 22:50:06 volumio volumio[971]: info: Folder /data/temp removed Apr 21 22:50:06 volumio volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:50:06 volumio volumio[971]: Error: Unable to resolve or reject the same promise twice Apr 21 22:50:06 volumio volumio[971]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 21 22:50:06 volumio volumio[971]: at /volumio/app/pluginmanager.js:1087:11 Apr 21 22:50:06 volumio volumio[971]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Apr 21 22:50:06 volumio volumio[971]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:190:7 Apr 21 22:50:06 volumio volumio[971]: at FSReqCallback.oncomplete (fs.js:171:23) Apr 21 22:50:06 volumio volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:50:07 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 22:49 Apr 21 22:50:07 volumio sudo[1402]: 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="b51559ff3f3719e35ff52cb9ccf2ff401b5ecb06" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="9f81dc1810002a5ad8861a42d98d57bc06a5d5d3" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 17 Apr 2024 07:54:00 PM CEST" VOLUMIO_VERSION="3.649" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="9c6dee1ade565aed57d77a70a260b988"