-- Logs begin at Thu 2019-02-14 12:12:07 EET, end at Sun 2024-10-20 21:15:40 EEST. -- Oct 20 21:14:05 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:05 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:05 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:05 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:15 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:15 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:18 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 20 21:14:25 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:25 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:25 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:25 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:33 volumiooldpi volumio[828]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/volroon/volumio/buster/armhf Oct 20 21:14:35 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:35 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:37 volumiooldpi volumio[828]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/volroon/volumio/buster/armhf Oct 20 21:14:37 volumiooldpi volumio[828]: info: Folder /tmp/plugins removed Oct 20 21:14:39 volumiooldpi volumio[828]: info: Check plugin dependencies Oct 20 21:14:39 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 21:14:39 volumiooldpi volumio[828]: info: Checking if plugin already exists Oct 20 21:14:39 volumiooldpi volumio[828]: info: Rename folder Oct 20 21:14:39 volumiooldpi volumio[828]: info: Folder /tmp/downloaded_plugin.zip removed Oct 20 21:14:40 volumiooldpi volumio[828]: info: Move to category Oct 20 21:14:46 volumiooldpi volumio[828]: info: Checking if install.sh is present Oct 20 21:14:46 volumiooldpi volumio[828]: info: Executing install.sh Oct 20 21:14:46 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:46 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:46 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:46 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:47 volumiooldpi sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/volroon/install.sh Oct 20 21:14:47 volumiooldpi sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:14:47 volumiooldpi volumio[828]: info: Installing volroon Dependencies Oct 20 21:14:47 volumiooldpi volumio[828]: verbose: New Socket.io Connection to 192.168.1.241 from 192.168.1.190 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Oct 20 21:14:47 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 21:14:47 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 21:14:47 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 21:14:47 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:47 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:48 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 20 21:14:48 volumiooldpi volumio[828]: info: Received Get System Info Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 21:14:48 volumiooldpi volumio[828]: info: Discovery: Getting this device information Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:48 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:48 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:14:48 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetQueue Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreStateMachine::getQueue Oct 20 21:14:48 volumiooldpi volumio[828]: info: CorePlayQueue::getQueue Oct 20 21:14:48 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 20 21:14:55 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:14:55 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:15:02 volumiooldpi volumio[828]: info: Enabling plugin volroon Oct 20 21:15:02 volumiooldpi volumio[828]: info: Loading plugin "volroon"... Oct 20 21:15:08 volumiooldpi volumio-remote-updater[457]: [2024-10-20 21:15:08] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 20 21:15:12 volumiooldpi volumio[828]: info: Preparing to generate the ALSA configuration file Oct 20 21:15:12 volumiooldpi volumio[828]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 21:15:12 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:15:12 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:15:12 volumiooldpi volumio[828]: info: CoreCommandRouter::volumioGetState Oct 20 21:15:12 volumiooldpi volumio[828]: info: CorePlayQueue::getTrack 0 Oct 20 21:15:13 volumiooldpi volumio[828]: info: Asound.conf file unchanged, so no further update is needed Oct 20 21:15:13 volumiooldpi volumio[828]: info: Output device has changed, restarting MPD Oct 20 21:15:13 volumiooldpi volumio-remote-updater[457]: [2024-10-20 21:15:13] [connect] Successful connection Oct 20 21:15:13 volumiooldpi volumio[828]: info: Output device has changed, restarting Shairport Sync Oct 20 21:15:13 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 21:15:13 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 21:15:13 volumiooldpi sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 20 21:15:13 volumiooldpi sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:15:13 volumiooldpi sudo[1678]: pam_unix(sudo:session): session closed for user root Oct 20 21:15:13 volumiooldpi sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 20 21:15:13 volumiooldpi sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:15:14 volumiooldpi systemd[1]: Stopping Music Player Daemon... Oct 20 21:15:15 volumiooldpi volumio[828]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 21:15:15 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 20 21:15:15 volumiooldpi systemd[1]: mpd.service: Succeeded. Oct 20 21:15:15 volumiooldpi systemd[1]: Stopped Music Player Daemon. Oct 20 21:15:16 volumiooldpi systemd[1]: Starting Music Player Daemon... Oct 20 21:15:16 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 21:15:16 volumiooldpi volumio[828]: info: volroon::Output device has changed Oct 20 21:15:16 volumiooldpi volumio[828]: info: PLUGIN START: volroon Oct 20 21:15:16 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 20 21:15:17 volumiooldpi sudo[1688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 20 21:15:17 volumiooldpi sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:15:17 volumiooldpi sudo[1688]: pam_unix(sudo:session): session closed for user root Oct 20 21:15:17 volumiooldpi volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 21:15:18 volumiooldpi sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 20 21:15:18 volumiooldpi sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:15:18 volumiooldpi sudo[1694]: pam_unix(sudo:session): session closed for user root Oct 20 21:15:19 volumiooldpi volumio[828]: info: volroon::Roon API Services Started Oct 20 21:15:19 volumiooldpi volumio[828]: info: Done. Oct 20 21:15:20 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:15:20 volumiooldpi volumio[828]: info: Listing playlists Oct 20 21:15:21 volumiooldpi volumio[828]: error: ::Cannot start Roon Bridge - Error: Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service Oct 20 21:15:21 volumiooldpi volumio[828]: Failed to start roonbridge.service: Unit roonbridge.service not found. Oct 20 21:15:21 volumiooldpi volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 21:15:21 volumiooldpi volumio[828]: Error: Unable to resolve or reject the same promise twice Oct 20 21:15:21 volumiooldpi volumio[828]: at Promise.reject (/data/plugins/music_service/volroon/node_modules/kew/kew.js:189:43) Oct 20 21:15:21 volumiooldpi volumio[828]: at /data/plugins/music_service/volroon/index.js:392:10 Oct 20 21:15:21 volumiooldpi volumio[828]: at ChildProcess.exithandler (child_process.js:315:5) Oct 20 21:15:21 volumiooldpi volumio[828]: at ChildProcess.emit (events.js:315:20) Oct 20 21:15:21 volumiooldpi volumio[828]: at maybeClose (internal/child_process.js:1048:16) Oct 20 21:15:21 volumiooldpi volumio[828]: at Socket. (internal/child_process.js:439:11) Oct 20 21:15:21 volumiooldpi volumio[828]: at Socket.emit (events.js:315:20) Oct 20 21:15:21 volumiooldpi volumio[828]: at Pipe. (net.js:673:12) Oct 20 21:15:21 volumiooldpi volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 21:15:25 volumiooldpi volumio-remote-updater[457]: [2024-10-20 21:15:25] [connect] Successful connection Oct 20 21:15:39 volumiooldpi sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-20 21:14 Oct 20 21:15:39 volumiooldpi sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 21:15:40 volumiooldpi volumio-remote-updater[457]: [2024-10-20 21:15:40] [connect] Successful connection 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"