-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-04-04 14:48:33 CEST. -- Apr 04 14:47:08 volumiostudio ntpd[677]: receive: Unexpected origin timestamp 0xe9b91fb4.41f06496 does not match aorg 0000000000.00000000 from server@129.152.16.145 xmt 0xe9b91fcc.410019d2 Apr 04 14:47:08 volumiostudio ntpd[677]: receive: Unexpected origin timestamp 0xe9b91fb4.41ef34b6 does not match aorg 0000000000.00000000 from server@37.247.53.178 xmt 0xe9b91fcc.40aadd68 Apr 04 14:47:08 volumiostudio ntpd[677]: receive: Unexpected origin timestamp 0xe9b91fb4.41edf361 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xe9b91fcc.408af498 Apr 04 14:47:08 volumiostudio ntpd[677]: receive: Unexpected origin timestamp 0xe9b91fb4.41e96a8e does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xe9b91fcc.41ea6d75 Apr 04 14:47:11 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 14:47:11 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 14:47:11 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:11 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 14:47:15 volumiostudio volumio[1000]: ...............................................................................................................................................................++++ Apr 04 14:47:15 volumiostudio volumio[1000]: e is 65537 (0x010001) Apr 04 14:47:15 volumiostudio volumio[1000]: writing RSA key Apr 04 14:47:16 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 12 Apr 04 14:47:16 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 13 Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 14:47:16 volumiostudio volumio[847]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 04 14:47:16 volumiostudio volumio[847]: info: Completed starting MyVolumio Plugin Apr 04 14:47:16 volumiostudio volumio[847]: info: BOOT COMPLETED Apr 04 14:47:16 volumiostudio volumio[847]: [Metrics] CommandRouter: 70s 678.19ms Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::volumiosetStartupVolume Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::Close All Modals sent Apr 04 14:47:16 volumiostudio volumio[847]: info: CoreCommandRouter::Close All Modals sent Apr 04 14:47:21 volumiostudio volumio-remote-updater[506]: No test mode Apr 04 14:47:21 volumiostudio volumio-remote-updater[506]: No alpha test mode Apr 04 14:47:21 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 04 14:47:21 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 04 14:47:21 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 14:47:21 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 04 14:47:21 volumiostudio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Apr 04 14:47:21 volumiostudio kernel: xhci_hcd 0000:01:00.0: @000000042a1131a0 00000000 00000000 0e000000 02028000 Apr 04 14:47:22 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 14 Apr 04 14:47:22 volumiostudio volumio[847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 04 14:47:22 volumiostudio volumio[847]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 04 14:47:22 volumiostudio volumio[847]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 04 14:47:22 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 04 14:47:34 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 16 Apr 04 14:47:34 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 17 Apr 04 14:47:34 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 18 Apr 04 14:47:35 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 19 Apr 04 14:47:36 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetState Apr 04 14:47:36 volumiostudio volumio[847]: info: CorePlayQueue::getTrack 0 Apr 04 14:47:37 volumiostudio volumio[847]: info: MyVolumio token set successfully Apr 04 14:47:37 volumiostudio volumio[847]: info: MYVOLUMIO: Adding device Apr 04 14:47:37 volumiostudio volumio[847]: info: MYVOLUMIO: Evaluating Server Apr 04 14:47:39 volumiostudio volumio[847]: info: MyVolumio status changed Apr 04 14:47:39 volumiostudio volumio[847]: info: Streaming services startup Apr 04 14:47:39 volumiostudio volumio[847]: info: Starting Streaming Daemon Apr 04 14:47:39 volumiostudio volumio[847]: info: Removing browser output: myVolumio user plan is not superstar Apr 04 14:47:39 volumiostudio volumio[847]: info: Removing audio output: Apr 04 14:47:39 volumiostudio volumio[847]: info: Stoppping Tunnel 1 Apr 04 14:47:39 volumiostudio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 14:47:39 volumiostudio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 14:47:39 volumiostudio sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 04 14:47:39 volumiostudio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 04 14:47:39 volumiostudio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 14:47:39 volumiostudio sudo[1598]: pam_unix(sudo:session): session closed for user root Apr 04 14:47:46 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 15 Apr 04 14:47:46 volumiostudio volumio[847]: error: Cannot start Volumio Streaming Daemon Apr 04 14:47:46 volumiostudio volumio[847]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 04 14:47:46 volumiostudio volumio[847]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 04 14:47:47 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 16 Apr 04 14:47:47 volumiostudio volumio[847]: info: Setting Geolocation for MyVolumio to eu10 Apr 04 14:47:47 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:47 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:47 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:47 volumiostudio volumio[847]: info: Remote SSH Stopped Apr 04 14:47:47 volumiostudio volumio[847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 17 Apr 04 14:47:48 volumiostudio volumio[847]: info: Updating MyVolumio device info Apr 04 14:47:48 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:48 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:48 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 14:47:51 volumiostudio volumio[847]: Cannot compose Albumart path Apr 04 14:47:53 volumiostudio volumio[847]: Cannot compose Albumart path Apr 04 14:47:53 volumiostudio volumio[847]: Cannot compose Albumart path Apr 04 14:48:03 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 18 Apr 04 14:48:03 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 19 Apr 04 14:48:03 volumiostudio volumio[847]: error: Failed to add MyVolumio device: undefined Apr 04 14:48:03 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 20 Apr 04 14:48:03 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetState Apr 04 14:48:03 volumiostudio volumio[847]: info: CorePlayQueue::getTrack 0 Apr 04 14:48:04 volumiostudio volumio[847]: info: Successfully Updated MyVolumio device Apr 04 14:48:06 volumiostudio volumio[847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 21 Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetState Apr 04 14:48:06 volumiostudio volumio[847]: info: CorePlayQueue::getTrack 0 Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 14:48:06 volumiostudio volumio[847]: info: Listing playlists Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 14:48:06 volumiostudio volumio[847]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 04 14:48:06 volumiostudio volumio[847]: info: Received Get System Version Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 14:48:06 volumiostudio volumio[847]: info: Received Get System Info Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 14:48:06 volumiostudio volumio[847]: info: Discovery: Getting this device information Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetState Apr 04 14:48:06 volumiostudio volumio[847]: info: CorePlayQueue::getTrack 0 Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::volumioGetState Apr 04 14:48:06 volumiostudio volumio[847]: info: CorePlayQueue::getTrack 0 Apr 04 14:48:06 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 14:48:06 volumiostudio volumio[847]: info: Listing playlists Apr 04 14:48:30 volumiostudio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 04 14:48:30 volumiostudio volumio[847]: info: CURURI: music-library/NAS/HiRes/SACD/Audiophile from FOCAL JMlab - 1996-2009 8CD (Malestrom) Apr 04 14:48:30 volumiostudio volumio[847]: info: Preload queue cleared Apr 04 14:48:32 volumiostudio volumio[847]: info: Preload queue cleared Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreStateMachine::ClearQueue Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreStateMachine::stop Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 04 14:48:32 volumiostudio volumio[847]: info: CorePlayQueue::clearPlayQueue Apr 04 14:48:32 volumiostudio volumio[847]: info: CorePlayQueue::saveQueue Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreCommandRouter::volumioPushQueue Apr 04 14:48:32 volumiostudio volumio[847]: info: CoreStateMachine::addQueueItems Apr 04 14:48:32 volumiostudio volumio[847]: info: CorePlayQueue::addQueueItems Apr 04 14:48:32 volumiostudio volumio[847]: info: Preload queue cleared Apr 04 14:48:32 volumiostudio volumio[847]: info: Adding Item to queue: music-library/NAS/HiRes/SACD/Audiophile from FOCAL JMlab - 1996-2009 8CD (Malestrom)/CD No 5 (2002) Apr 04 14:48:32 volumiostudio volumio[847]: info: Exploding uri music-library/NAS/HiRes/SACD/Audiophile from FOCAL JMlab - 1996-2009 8CD (Malestrom)/CD No 5 (2002) in service mpd Apr 04 14:48:32 volumiostudio volumio[847]: info: ALBUMART /albumart?cacheid=61&web=Oleta%20Adams/Focal%20JMlab%20CD%20%3F5%2F7%20/extralarge&path=%2Fmnt%2FNAS%2FHiRes%2FSACD%2FAudiophile%20from%20FOCAL%20JMlab%20-%201996-2009%208CD%20(Malestrom)%2FCD%20No%205%20(2002)%2FVarious%20-%20Focal%20JMlab%20CD%205.cue&metadata=false Apr 04 14:48:32 volumiostudio volumio[847]: info: URI /mnt/NAS/HiRes/SACD/Audiophile from FOCAL JMlab - 1996-2009 8CD (Malestrom)/CD No 5 (2002)/Various - Focal JMlab CD 5.cue Apr 04 14:48:32 volumiostudio volumio[847]: info: ALBUMART /albumart?cacheid=61&web=Souad%20Massi/Focal%20JMlab%20CD%20%3F5%2F7%20/extralarge&path=%2Fmnt%2FNAS%2FHiRes%2FSACD%2FAudiophile%20from%20FOCAL%20JMlab%20-%201996-2009%208CD%20(Malestrom)%2FCD%20No%205%20(2002)%2FVarious%20-%20Focal%20JMlab%20CD%205.cue&metadata=false Apr 04 14:48:32 volumiostudio volumio[847]: info: URI /mnt/NAS/HiRes/SACD/Audiophile from FOCAL JMlab - 1996-2009 8CD (Malestrom)/CD No 5 (2002)/Various - Focal JMlab CD 5.cue Apr 04 14:48:32 volumiostudio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 14:48:32 volumiostudio volumio[847]: Error: Unable to resolve or reject the same promise twice Apr 04 14:48:32 volumiostudio volumio[847]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 04 14:48:32 volumiostudio volumio[847]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 04 14:48:32 volumiostudio volumio[847]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 04 14:48:32 volumiostudio volumio[847]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 04 14:48:32 volumiostudio volumio[847]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 04 14:48:32 volumiostudio volumio[847]: at Socket.emit (events.js:315:20) Apr 04 14:48:32 volumiostudio volumio[847]: at addChunk (internal/streams/readable.js:309:12) Apr 04 14:48:32 volumiostudio volumio[847]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 04 14:48:32 volumiostudio volumio[847]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 04 14:48:32 volumiostudio volumio[847]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 04 14:48:32 volumiostudio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 04 14:48:33 volumiostudio sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-04 14:47 Apr 04 14:48:33 volumiostudio sudo[1652]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"