-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Fri 2024-10-11 19:52:20 EEST. -- Oct 11 19:51:13 volumio ntpd[554]: receive: Unexpected origin timestamp 0xe9ce8847.d899edf1 does not match aorg 0000000000.00000000 from server@85.10.240.253 xmt 0xeab3d601.cad9734c Oct 11 19:51:13 volumio ntpd[554]: receive: Unexpected origin timestamp 0xe9ce8847.d893381d does not match aorg 0000000000.00000000 from server@192.53.103.108 xmt 0xeab3d601.cb72dcfe Oct 11 19:51:13 volumio ntpd[554]: receive: Unexpected origin timestamp 0xe9ce8847.d8968de2 does not match aorg 0000000000.00000000 from server@62.169.23.198 xmt 0xeab3d601.cab7d9d6 Oct 11 19:51:13 volumio ntpd[554]: receive: Unexpected origin timestamp 0xe9ce8847.d88f435e does not match aorg 0000000000.00000000 from server@194.164.164.175 xmt 0xeab3d601.cdc03b6e Oct 11 19:51:13 volumio systemd[1]: Starting Daily apt download activities... Oct 11 19:51:15 volumio systemd[1]: apt-daily.service: Succeeded. Oct 11 19:51:16 volumio systemd[1]: Started Daily apt download activities. Oct 11 19:51:16 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 11 19:51:17 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 11 19:51:17 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 11 19:51:18 volumio volumio[584]: error: MyVolumio Plugin failed to start in a timely fashion Oct 11 19:51:18 volumio volumio[584]: info: BOOT COMPLETED Oct 11 19:51:18 volumio volumio[584]: [Metrics] CommandRouter: 70s 123.15ms Oct 11 19:51:18 volumio volumio[584]: info: CoreCommandRouter::volumiosetStartupVolume Oct 11 19:51:18 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:51:18 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:51:18 volumio volumio[584]: info: CoreCommandRouter::Close All Modals sent Oct 11 19:51:19 volumio volumio[584]: info: CoreCommandRouter::Close All Modals sent Oct 11 19:51:19 volumio volumio[584]: info: Discovery: A device disappeared from network Oct 11 19:51:19 volumio volumio[584]: info: Discovery: Device volumio disappeared from network Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin bluetooth to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin multiroom to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin metavolumio to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin cd_controller to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 11 19:51:19 volumio volumio[584]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 11 19:51:22 volumio volumio[584]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 11 19:51:22 volumio volumio[584]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 11 19:51:22 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:51:22 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:51:22 volumio volumio[584]: info: Starting MyVolumio Remote Streaming Endpoints Oct 11 19:51:22 volumio volumio[584]: info: MyVolumio login type: Token Oct 11 19:51:22 volumio volumio[584]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 11 19:51:22 volumio volumio[584]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 11 19:51:23 volumio nmbd[527]: [2024/10/11 19:51:23.313851, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 11 19:51:23 volumio nmbd[527]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.82.102 for name WORKGROUP<1d>. Oct 11 19:51:23 volumio nmbd[527]: This response was from IP 192.168.82.40, reporting an IP address of 192.168.82.40. Oct 11 19:51:25 volumio volumio[584]: info: Starting Streaming Service Transparent Proxy Oct 11 19:51:25 volumio volumio[584]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 11 19:51:25 volumio volumio[584]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 11 19:51:25 volumio volumio[584]: info: Streaming services startup Oct 11 19:51:25 volumio volumio[584]: info: Starting Streaming Daemon Oct 11 19:51:25 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 19:51:25 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:51:25 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Oct 11 19:51:25 volumio volumio[584]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:51:25 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 19:51:26 volumio volumio[584]: error: Cannot start Volumio Streaming Daemon Oct 11 19:51:26 volumio volumio[584]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 19:51:26 volumio volumio[584]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 19:51:26 volumio volumio[811]: ................................................................................++++ Oct 11 19:51:26 volumio volumio[811]: e is 65537 (0x010001) Oct 11 19:51:26 volumio volumio[811]: writing RSA key Oct 11 19:51:28 volumio volumio[584]: STREAMING PROXY: Starting server on port 3245 Oct 11 19:51:28 volumio volumio[584]: Node JS runtime: 14 Oct 11 19:51:31 volumio volumio[584]: info: Discovery: adding 35b6ff4b-d7d2-46e6-913d-cb1de681c6ef Oct 11 19:51:31 volumio volumio[584]: info: Discovery: Found device Volumio Oct 11 19:51:31 volumio volumio[584]: info: CoreCommandRouter::volumioGetState Oct 11 19:51:31 volumio volumio[584]: info: CorePlayQueue::getTrack 0 Oct 11 19:51:31 volumio volumio[584]: info: CoreCommandRouter::volumioGetState Oct 11 19:51:31 volumio volumio[584]: info: CorePlayQueue::getTrack 0 Oct 11 19:51:33 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 11 19:51:33 volumio volumio[584]: info: CURURI: music-library/NAS/downloads/flac Oct 11 19:51:34 volumio volumio-remote-updater[371]: No test mode Oct 11 19:51:34 volumio volumio-remote-updater[371]: No alpha test mode Oct 11 19:51:34 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 19:51:35 volumio volumio[584]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS



 

","title":"Update v3.757","updateavailable":true} Oct 11 19:51:35 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 19:51:36 volumio volumio[584]: info: Preload queue cleared Oct 11 19:51:36 volumio volumio[584]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 19:51:36 volumio volumio[584]: info: CoreStateMachine::ClearQueue Oct 11 19:51:36 volumio volumio[584]: info: CoreStateMachine::stop Oct 11 19:51:36 volumio volumio[584]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 19:51:36 volumio volumio[584]: info: CorePlayQueue::clearPlayQueue Oct 11 19:51:36 volumio volumio[584]: info: CorePlayQueue::saveQueue Oct 11 19:51:36 volumio volumio[584]: info: CoreCommandRouter::volumioPushQueue Oct 11 19:51:36 volumio volumio[584]: info: CoreStateMachine::addQueueItems Oct 11 19:51:36 volumio volumio[584]: info: CorePlayQueue::addQueueItems Oct 11 19:51:36 volumio volumio[584]: info: Preload queue cleared Oct 11 19:51:36 volumio volumio[584]: info: Adding Item to queue: music-library/NAS/downloads/flac Oct 11 19:51:36 volumio volumio[584]: info: Exploding uri music-library/NAS/downloads/flac in service mpd Oct 11 19:51:49 volumio volumio[584]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 19:51:49 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 19:51:52 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 11 19:51:52 volumio volumio[584]: info: CURURI: music-library/NAS/downloads/flac Oct 11 19:51:52 volumio volumio[584]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 11 19:51:56 volumio volumio[584]: info: MyVolumio token set successfully Oct 11 19:51:56 volumio volumio[584]: info: MYVOLUMIO: Adding device Oct 11 19:51:56 volumio volumio[584]: info: MYVOLUMIO: Evaluating Server Oct 11 19:51:59 volumio volumio[584]: info: MyVolumio status changed Oct 11 19:51:59 volumio volumio[584]: info: Streaming services startup Oct 11 19:51:59 volumio volumio[584]: info: Starting Streaming Daemon Oct 11 19:51:59 volumio volumio[584]: info: Removing browser output: myVolumio user plan is not superstar Oct 11 19:51:59 volumio volumio[584]: info: Removing audio output: Oct 11 19:51:59 volumio volumio[584]: info: Stoppping Tunnel 1 Oct 11 19:51:59 volumio sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 19:51:59 volumio sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:52:00 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 11 19:52:00 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:52:00 volumio sudo[1106]: pam_unix(sudo:session): session closed for user root Oct 11 19:52:00 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Oct 11 19:52:01 volumio volumio[584]: info: CoreCommandRouter::volumioGetState Oct 11 19:52:01 volumio volumio[584]: info: CorePlayQueue::getTrack 0 Oct 11 19:52:01 volumio volumio[584]: info: Remote SSH Stopped Oct 11 19:52:01 volumio volumio[584]: error: Cannot start Volumio Streaming Daemon Oct 11 19:52:01 volumio volumio[584]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 19:52:01 volumio volumio[584]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 19:52:06 volumio volumio[584]: info: Setting Geolocation for MyVolumio to eu4 Oct 11 19:52:06 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:06 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:06 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:08 volumio sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 19:52:08 volumio sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:52:08 volumio sudo[1112]: pam_unix(sudo:session): session closed for user root Oct 11 19:52:08 volumio sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 19:52:08 volumio sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 19:52:08 volumio volumio[584]: info: Updating MyVolumio device info Oct 11 19:52:08 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:08 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:08 volumio volumio[584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 19:52:08 volumio sudo[1115]: pam_unix(sudo:session): session closed for user root Oct 11 19:52:19 volumio volumio[584]: info: Preload queue cleared Oct 11 19:52:19 volumio volumio[584]: info: ALBUMART /albumart?cacheid=165&web=A-ha/Greatest%20Hits%20-%20Japanese%20Single%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fdownloads%2Fflac%2FA-ha%20-%20Greatest%20Hits%20-%20Japanese%20Single%20Collection%20-%202020%2FA-ha%20-%20Greatest%20Hits%20-%20Japanese%20Single%20Collection.cue&metadata=false Oct 11 19:52:19 volumio volumio[584]: info: URI /mnt/NAS/downloads/flac/A-ha - Greatest Hits - Japanese Single Collection - 2020/A-ha - Greatest Hits - Japanese Single Collection.cue Oct 11 19:52:19 volumio volumio[584]: info: ALBUMART /albumart?cacheid=165&web=A-ha/Greatest%20Hits%20-%20Japanese%20Single%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fdownloads%2Fflac%2FA-ha%20-%20Greatest%20Hits%20-%20Japanese%20Single%20Collection%20-%202020%2FA-ha%20-%20Greatest%20Hits%20-%20Japanese%20Single%20Collection.cue&metadata=false Oct 11 19:52:19 volumio volumio[584]: info: URI /mnt/NAS/downloads/flac/A-ha - Greatest Hits - Japanese Single Collection - 2020/A-ha - Greatest Hits - Japanese Single Collection.cue Oct 11 19:52:19 volumio volumio[584]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:52:19 volumio volumio[584]: Error: Unable to resolve or reject the same promise twice Oct 11 19:52:19 volumio volumio[584]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 11 19:52:19 volumio volumio[584]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 11 19:52:19 volumio volumio[584]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 11 19:52:19 volumio volumio[584]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 11 19:52:19 volumio volumio[584]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 11 19:52:19 volumio volumio[584]: at Socket.emit (events.js:315:20) Oct 11 19:52:19 volumio volumio[584]: at addChunk (internal/streams/readable.js:309:12) Oct 11 19:52:19 volumio volumio[584]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 11 19:52:19 volumio volumio[584]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 11 19:52:19 volumio volumio[584]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 11 19:52:19 volumio volumio[584]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:52:20 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 19:51 Oct 11 19:52:20 volumio sudo[1129]: 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"