-- 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"