-- Logs begin at Mon 2024-07-22 13:22:29 EDT, end at Mon 2024-09-02 18:50:44 EDT. -- Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99d9eda6 does not match aorg 0000000000.00000000 from server@23.186.168.1 xmt 0xea80bfad.6a82b14e Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99d3ae5b does not match aorg 0000000000.00000000 from server@135.148.100.14 xmt 0xea80bfad.6e7155e7 Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99a5b7da does not match aorg 0000000000.00000000 from server@208.113.130.146 xmt 0xea80bfad.6b4eba04 Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99bfefcd does not match aorg 0000000000.00000000 from server@50.218.103.254 xmt 0xea80bfad.6956c4cf Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99cc47b4 does not match aorg 0000000000.00000000 from server@65.100.46.166 xmt 0xea80bfad.6fd5be42 Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99e676b9 does not match aorg 0000000000.00000000 from server@129.146.193.200 xmt 0xea80bfad.73a5253e Sep 02 18:50:21 volumio1 ntpd[526]: receive: Unexpected origin timestamp 0xea491440.99e066ee does not match aorg 0000000000.00000000 from server@45.83.234.123 xmt 0xea80bfad.76b27634 Sep 02 18:50:21 volumio1 systemd[1]: Starting Daily apt download activities... Sep 02 18:50:21 volumio1 systemd[1]: Listening on mpd.socket. Sep 02 18:50:21 volumio1 systemd[1]: Starting Music Player Daemon... Sep 02 18:50:22 volumio1 volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:50:22 volumio1 volumio[794]: info: ___________ START PLUGINS ___________ Sep 02 18:50:22 volumio1 volumio[794]: info: ControllerMpd::onStart: Initializing MPD Sep 02 18:50:22 volumio1 volumio[794]: info: Creating MPD Configuration file Sep 02 18:50:22 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 18:50:23 volumio1 sudo[966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 18:50:23 volumio1 sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:50:23 volumio1 volumio[794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:50:23 volumio1 volumio[794]: info: [1725317423098] CoreMusicLibrary::Adding element Media Servers Sep 02 18:50:23 volumio1 sudo[966]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 02 18:50:23 volumio1 sudo[966]: pam_unix(sudo:session): session closed for user root Sep 02 18:50:23 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:50:23 volumio1 sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 18:50:23 volumio1 sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:50:23 volumio1 sudo[972]: pam_unix(sudo:session): session closed for user root Sep 02 18:50:23 volumio1 sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 18:50:23 volumio1 sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:50:24 volumio1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 18:50:24 volumio1 systemd[1]: mpd.service: Succeeded. Sep 02 18:50:24 volumio1 systemd[1]: Stopped Music Player Daemon. Sep 02 18:50:24 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:50:24 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:50:24 volumio1 systemd[1]: Starting Music Player Daemon... Sep 02 18:50:25 volumio1 volumio[794]: Starting albumart workers Sep 02 18:50:25 volumio1 volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:50:25 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 18:50:25 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:50:25 volumio1 sudo[992]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 18:50:25 volumio1 sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 18:50:25 volumio1 sudo[992]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 02 18:50:25 volumio1 sudo[992]: pam_unix(sudo:session): session closed for user root Sep 02 18:50:26 volumio1 volumio[794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:50:26 volumio1 volumio[794]: info: [1725317426161] CoreMusicLibrary::Adding element Last_100 Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:50:26 volumio1 volumio[794]: info: [1725317426256] CoreMusicLibrary::Adding element Webradio Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 18:50:26 volumio1 volumio[794]: info: Initializing BBC Radios Sep 02 18:50:26 volumio1 volumio[794]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Sep 02 18:50:26 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:50:27 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 18:50:27 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 18:50:28 volumio1 volumio[794]: info: Discovery: Getting this device information Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetState Sep 02 18:50:28 volumio1 volumio[794]: info: CorePlayQueue::getTrack 0 Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 18:50:28 volumio1 volumio[794]: info: [1725317428313] CoreMusicLibrary::Adding element Jellyfin Sep 02 18:50:28 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:50:28 volumio1 volumio[794]: Cannot find translation for source Jellyfin Sep 02 18:50:28 volumio1 volumio[794]: info: [jellyfin] Initialized plugin with device info: {"id":"58087d92-5b32-44b6-847c-d13a0aeb770a","host":"http://192.168.1.96","name":"Volumio1","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":87,"mute":false,"artist":"The Beatles","track":"Come Together","albumart":"http://192.168.1.96/albumart?cacheid=111&web=The%20Beatles/Abbey%20Road/extralarge&path=%2FNAS%2FNAS_Dir_3%2FThe%20Beatles%2FAbbey%20Road&metadata=false"}} Sep 02 18:50:28 volumio1 volumio[794]: info: Volumio Calling Home Sep 02 18:50:29 volumio1 nmbd[519]: [2024/09/02 18:50:29.040418, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 18:50:29 volumio1 nmbd[519]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.96 for name WORKGROUP<1d>. Sep 02 18:50:29 volumio1 nmbd[519]: This response was from IP 192.168.1.99, reporting an IP address of 192.168.1.99. Sep 02 18:50:29 volumio1 nmbd[519]: [2024/09/02 18:50:29.053961, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 18:50:29 volumio1 nmbd[519]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.96 for name WORKGROUP<1d>. Sep 02 18:50:29 volumio1 nmbd[519]: This response was from IP 192.168.1.99, reporting an IP address of 192.168.1.99. Sep 02 18:50:29 volumio1 nmbd[519]: [2024/09/02 18:50:29.063082, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 02 18:50:29 volumio1 nmbd[519]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.96 for name WORKGROUP<1d>. Sep 02 18:50:29 volumio1 nmbd[519]: This response was from IP 192.168.1.99, reporting an IP address of 192.168.1.99. Sep 02 18:50:29 volumio1 systemd[1]: apt-daily.service: Succeeded. Sep 02 18:50:29 volumio1 systemd[1]: Started Daily apt download activities. Sep 02 18:50:29 volumio1 systemd[1]: Starting Daily apt upgrade and clean activities... Sep 02 18:50:35 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 02 18:50:36 volumio1 volumio[794]: info: MPD Permissions set Sep 02 18:50:36 volumio1 volumio[794]: info: MPD Permissions set Sep 02 18:50:36 volumio1 volumio[794]: info: Discovery: A device disappeared from network Sep 02 18:50:36 volumio1 volumio[794]: info: Discovery: Device volumio1 disappeared from network Sep 02 18:50:36 volumio1 systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 02 18:50:36 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Sep 02 18:50:37 volumio1 volumio[794]: info: Volumio called home Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetState Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::getTrack 0 Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CoreStateMachine::getQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::getQueue Sep 02 18:50:38 volumio1 volumio[794]: info: Listing playlists Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 18:50:38 volumio1 volumio[794]: info: Received Get System Info Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 18:50:38 volumio1 volumio[794]: info: Discovery: Getting this device information Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetState Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::getTrack 0 Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioGetState Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::getTrack 0 Sep 02 18:50:38 volumio1 volumio[794]: info: Preload queue cleared Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 18:50:38 volumio1 volumio[794]: info: CoreStateMachine::ClearQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CoreStateMachine::stop Sep 02 18:50:38 volumio1 volumio[794]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::clearPlayQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CorePlayQueue::saveQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CoreCommandRouter::volumioPushQueue Sep 02 18:50:38 volumio1 volumio[794]: info: CoreStateMachine::addQueueItems Sep 02 18:50:39 volumio1 volumio[794]: info: CorePlayQueue::addQueueItems Sep 02 18:50:39 volumio1 volumio[794]: info: Preload queue cleared Sep 02 18:50:39 volumio1 volumio[794]: info: Adding Item to queue: music-library/NAS/NAS_Dir_3/The Beatles/Abbey Road/Come Together.mp3 Sep 02 18:50:39 volumio1 volumio[794]: info: Exploding uri music-library/NAS/NAS_Dir_3/The Beatles/Abbey Road/Come Together.mp3 in service mpd Sep 02 18:50:39 volumio1 volumio[794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 18:50:39 volumio1 volumio[794]: TypeError: Cannot read property 'then' of undefined Sep 02 18:50:39 volumio1 volumio[794]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2527:19) Sep 02 18:50:39 volumio1 volumio[794]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2325:21) Sep 02 18:50:39 volumio1 volumio[794]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1365:16) Sep 02 18:50:39 volumio1 volumio[794]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Sep 02 18:50:39 volumio1 volumio[794]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Sep 02 18:50:39 volumio1 volumio[794]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Sep 02 18:50:39 volumio1 volumio[794]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Sep 02 18:50:39 volumio1 volumio[794]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Sep 02 18:50:39 volumio1 volumio[794]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:483:17) Sep 02 18:50:39 volumio1 volumio[794]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 02 18:50:39 volumio1 volumio[794]: at Socket.emit (events.js:315:20) Sep 02 18:50:39 volumio1 volumio[794]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 02 18:50:39 volumio1 volumio[794]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 02 18:50:39 volumio1 volumio[794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 18:50:43 volumio1 sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 18:49 Sep 02 18:50:44 volumio1 sudo[1114]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"