-- Logs begin at Mon 2024-04-22 19:32:47 CEST, end at Tue 2024-04-23 09:51:33 CEST. -- Apr 23 09:51:28 motivo sudo[17659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 09:51:28 motivo sudo[17659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:28 motivo sudo[17659]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:28 motivo sudo[17662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 09:51:28 motivo sudo[17662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:28 motivo sudo[17662]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:28 motivo volumio[830]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 Apr 23 09:51:28 motivo sudo[17665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 09:51:28 motivo sudo[17665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:28 motivo sudo[17665]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:28 motivo sudo[17668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 09:51:28 motivo sudo[17668]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:28 motivo sudo[17668]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:28 motivo volumio[830]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 9 Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:28 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 23 09:51:28 motivo volumio[830]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 23 09:51:28 motivo volumio[830]: info: Received Get System Info Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 09:51:28 motivo volumio[830]: info: Discovery: Getting this device information Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:28 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:28 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:28 motivo volumio[830]: info: Listing playlists Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 09:51:28 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 23 09:51:29 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:29 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 09:51:30 motivo volumio[830]: info: Received Get System Info Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 09:51:30 motivo volumio[830]: info: Discovery: Getting this device information Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:30 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 09:51:30 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 23 09:51:30 motivo volumio[830]: info: MyVolumio login type: Token Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 09:51:31 motivo volumio[830]: info: Received Get System Info Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 09:51:31 motivo volumio[830]: info: Discovery: Getting this device information Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::volumioGetState Apr 23 09:51:31 motivo volumio[830]: info: CorePlayQueue::getTrack 0 Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 09:51:31 motivo volumio[830]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 23 09:51:31 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 23 09:51:31 motivo volumio[830]: info: MyVolumio login type: Token Apr 23 09:51:32 motivo volumio[830]: info: MyVolumio token set successfully Apr 23 09:51:32 motivo volumio[830]: info: MYVOLUMIO: Adding device Apr 23 09:51:32 motivo volumio[830]: info: MYVOLUMIO: Evaluating Server Apr 23 09:51:32 motivo volumio[830]: info: MyVolumio Plan changed: superstar Apr 23 09:51:32 motivo volumio[830]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 23 09:51:32 motivo volumio[830]: info: Adding audio output: Apr 23 09:51:32 motivo volumio[830]: error: Can't add: Apr 23 09:51:32 motivo volumio[830]: info: MYVOLUMIO: Adding device Apr 23 09:51:32 motivo volumio[830]: info: MYVOLUMIO: Evaluating Server Apr 23 09:51:32 motivo volumio[830]: info: Remote config written successfully Apr 23 09:51:32 motivo volumio[830]: info: Starting Tunnel 1 Apr 23 09:51:32 motivo volumio[830]: info: Starting Tunnel Connection Checker Apr 23 09:51:32 motivo sudo[17696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 23 09:51:32 motivo sudo[17696]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:32 motivo autossh[8962]: received signal to exit (15) Apr 23 09:51:32 motivo systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 23 09:51:32 motivo systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 23 09:51:32 motivo systemd[1]: sshtunnel.service: Succeeded. Apr 23 09:51:32 motivo systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 23 09:51:32 motivo systemd[1]: Started MyVolumio SSH Tunnel. Apr 23 09:51:32 motivo sudo[17696]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:32 motivo volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 09:51:32 motivo volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 23 09:51:32 motivo autossh[17699]: port set to 0, monitoring disabled Apr 23 09:51:32 motivo autossh[17699]: starting ssh (count 1) Apr 23 09:51:32 motivo autossh[17699]: ssh child pid is 17702 Apr 23 09:51:32 motivo volumiossh-tunnel[17698]: Warning: Permanently added '[eu5.myvolumio.org]:2222,[185.14.184.111]:2222' (RSA) to the list of known hosts. Apr 23 09:51:33 motivo volumio[830]: info: MYVolumio Device enabled Apr 23 09:51:33 motivo volumio[830]: info: MyVolumio status changed Apr 23 09:51:33 motivo volumio[830]: info: Streaming services startup Apr 23 09:51:33 motivo volumio[830]: info: Starting Streaming Daemon Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 23 09:51:33 motivo volumio[830]: info: Setting Geolocation for MyVolumio to eu5 Apr 23 09:51:33 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 09:51:33 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 09:51:33 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 23 09:51:33 motivo sudo[17720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 23 09:51:33 motivo sudo[17720]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 09:51:33 motivo volumio[830]: info: Remote SSH Started Apr 23 09:51:33 motivo sudo[17720]: pam_unix(sudo:session): session closed for user root Apr 23 09:51:33 motivo volumio[830]: error: Cannot start Volumio Streaming Daemon Apr 23 09:51:33 motivo volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 23 09:51:33 motivo volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 23 09:51:33 motivo volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 23 09:51:33 motivo volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 23 09:51:33 motivo volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 09:51:33 motivo volumio[830]: Error: Unable to resolve or reject the same promise twice Apr 23 09:51:33 motivo volumio[830]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 23 09:51:33 motivo volumio[830]: at Array. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5676) Apr 23 09:51:33 motivo volumio[830]: at Promise.reject (/volumio/node_modules/kew/kew.js:204:26) Apr 23 09:51:33 motivo volumio[830]: at nextTickCallback (/volumio/node_modules/kew/kew.js:49:11) Apr 23 09:51:33 motivo volumio[830]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 23 09:51:33 motivo volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 09:51:33 motivo sudo[17731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-23 09:50 Apr 23 09:51:33 motivo sudo[17731]: 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="b51559ff3f3719e35ff52cb9ccf2ff401b5ecb06" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="f61678c696e00921b9f86a2ceee67cca65b9d5c8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Apr 2024 07:23:49 PM CEST" VOLUMIO_VERSION="3.654" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="5fa8aed88b82db1221cbacf1bdbb9025"