-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-07-10 10:23:08 CEST. -- Jul 10 10:22:24 volumio systemd[1]: Starting Daily apt download activities... Jul 10 10:22:24 volumio systemd[1]: Starting Daily man-db regeneration... Jul 10 10:22:25 volumio systemd[1]: man-db.service: Succeeded. Jul 10 10:22:25 volumio systemd[1]: Started Daily man-db regeneration. Jul 10 10:22:26 volumio volumio[1020]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 10 10:22:26 volumio volumio[1020]: info: Completed starting MyVolumio Plugin Jul 10 10:22:26 volumio volumio[1020]: info: BOOT COMPLETED Jul 10 10:22:26 volumio volumio[1020]: [Metrics] CommandRouter: 55s 594.82ms Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::volumiosetStartupVolume Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 10:22:26 volumio volumio[1020]: info: VolumeController:: Setting startup Volume 65 Jul 10 10:22:26 volumio volumio[1020]: info: VolumeController::SetAlsaVolume65 Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Jul 10 10:22:26 volumio volumio[1020]: info: CoreStateMachine::pushState Jul 10 10:22:26 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 10:22:26 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Jul 10 10:22:26 volumio volumio[1020]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Jul 10 10:22:26 volumio volumio[1020]: SPOTIFY: SPOTIFY VOLUME 75 Jul 10 10:22:26 volumio volumio[1020]: SPOTIFY: VOLUMIO VOLUME 65 Jul 10 10:22:26 volumio volumio[1020]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 10 10:22:26 volumio volumio[1020]: info: Setting Spotify Volume from Volumio: 65 Jul 10 10:22:27 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 10 10:22:27 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 10:22:27 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 10 10:22:28 volumio volumio[1020]: SPOTIFY: SETTING SPOTIFY VOLUME 65 Jul 10 10:22:28 volumio volumio[1020]: info: Sending Spotify command with payload to local API: /player/volume Jul 10 10:22:28 volumio go-librespot[1209]: time="2024-07-10T10:22:28+02:00" level=debug msg="update volume to 42597/65535" Jul 10 10:22:28 volumio volumio[1020]: info: Discovery: A device disappeared from network Jul 10 10:22:28 volumio volumio[1020]: info: Discovery: Device volumio disappeared from network Jul 10 10:22:28 volumio go-librespot[1209]: time="2024-07-10T10:22:28+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 10:22:28 volumio go-librespot[1209]: time="2024-07-10T10:22:28+02:00" level=trace msg="emitting websocket event: volume" Jul 10 10:22:28 volumio volumio[1020]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Jul 10 10:22:28 volumio volumio[1020]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Jul 10 10:22:28 volumio volumio[1020]: info: Discovery: adding 91f0de6b-c145-49a5-bf96-80f9453a09c4 Jul 10 10:22:28 volumio volumio[1020]: info: Discovery: Found device Volumio Jul 10 10:22:28 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Jul 10 10:22:28 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Jul 10 10:22:29 volumio systemd[1]: apt-daily.service: Succeeded. Jul 10 10:22:29 volumio systemd[1]: Started Daily apt download activities. Jul 10 10:22:29 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jul 10 10:22:32 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 10 10:22:32 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jul 10 10:23:04 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 10:23:04 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 10:23:04 volumio volumio[1020]: info: Discovery: Getting this device information Jul 10 10:23:04 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Jul 10 10:23:04 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Jul 10 10:23:04 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 10:23:07 volumio volumio[1020]: info: Preload queue cleared Jul 10 10:23:07 volumio volumio[1020]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 10:23:07 volumio volumio[1020]: info: CoreStateMachine::ClearQueue Jul 10 10:23:07 volumio volumio[1020]: info: CoreStateMachine::stop Jul 10 10:23:07 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 10:23:07 volumio volumio[1020]: info: CorePlayQueue::clearPlayQueue Jul 10 10:23:07 volumio volumio[1020]: info: CorePlayQueue::saveQueue Jul 10 10:23:07 volumio volumio[1020]: info: CoreCommandRouter::volumioPushQueue Jul 10 10:23:07 volumio volumio[1020]: info: CoreStateMachine::addQueueItems Jul 10 10:23:07 volumio volumio[1020]: info: CorePlayQueue::addQueueItems Jul 10 10:23:07 volumio volumio[1020]: info: Preload queue cleared Jul 10 10:23:07 volumio volumio[1020]: info: Adding Item to queue: music-library/NAS/Nas/Perfect - Live (1982) (2013) [Z3K] Jul 10 10:23:07 volumio volumio[1020]: info: Exploding uri music-library/NAS/Nas/Perfect - Live (1982) (2013) [Z3K] in service mpd Jul 10 10:23:07 volumio volumio[1020]: info: ALBUMART /albumart?cacheid=463&web=/Perfect%20Live/extralarge&path=%2Fmnt%2FNAS%2FNas%2FPerfect%20-%20Live%20(1982)%20(2013)%20%5BZ3K%5D%2FPerfect%20-%20Perfect%20Live.cue&metadata=false Jul 10 10:23:07 volumio volumio[1020]: info: URI /mnt/NAS/Nas/Perfect - Live (1982) (2013) [Z3K]/Perfect - Perfect Live.cue Jul 10 10:23:07 volumio volumio[1020]: info: ALBUMART /albumart?cacheid=463&web=/Perfect%20Live/extralarge&path=%2Fmnt%2FNAS%2FNas%2FPerfect%20-%20Live%20(1982)%20(2013)%20%5BZ3K%5D%2FPerfect%20-%20Perfect%20Live.cue&metadata=false Jul 10 10:23:07 volumio volumio[1020]: info: URI /mnt/NAS/Nas/Perfect - Live (1982) (2013) [Z3K]/Perfect - Perfect Live.cue Jul 10 10:23:07 volumio volumio[1020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 10:23:07 volumio volumio[1020]: Error: Unable to resolve or reject the same promise twice Jul 10 10:23:07 volumio volumio[1020]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 10 10:23:07 volumio volumio[1020]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Jul 10 10:23:07 volumio volumio[1020]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jul 10 10:23:07 volumio volumio[1020]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jul 10 10:23:07 volumio volumio[1020]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jul 10 10:23:07 volumio volumio[1020]: at Socket.emit (events.js:315:20) Jul 10 10:23:07 volumio volumio[1020]: at addChunk (internal/streams/readable.js:309:12) Jul 10 10:23:07 volumio volumio[1020]: at readableAddChunk (internal/streams/readable.js:280:11) Jul 10 10:23:07 volumio volumio[1020]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jul 10 10:23:07 volumio volumio[1020]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 10 10:23:07 volumio volumio[1020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 10:23:08 volumio sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 10:22 Jul 10 10:23:08 volumio sudo[1475]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"