-- Logs begin at Wed 2024-04-24 14:02:38 UTC, end at Wed 2024-05-01 09:28:14 UTC. -- May 01 09:27:16 motivo systemd[1]: Starting Daily apt download activities... May 01 09:27:16 motivo systemd[1]: Starting Daily man-db regeneration... May 01 09:27:16 motivo volumio[2647]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 09:27:16 motivo volumio[2647]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 09:27:16 motivo volumio[2647]: info: Discovery: A device disappeared from network May 01 09:27:16 motivo volumio[2647]: info: Discovery: Device integro disappeared from network May 01 09:27:16 motivo volumio[2647]: info: Discovery: Disconnected from remote: 192.168.1.66 May 01 09:27:16 motivo volumio[2647]: info: Discovery: A device disappeared from network May 01 09:27:16 motivo volumio[2647]: info: Discovery: Device motivo disappeared from network May 01 09:27:16 motivo volumio[2647]: info: Executing endpoint tc_getconfig May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 01 09:27:16 motivo vtcs[2963]: STARTING TidalConnect services, version: 1.4.0.34 May 01 09:27:16 motivo systemd[1]: man-db.service: Succeeded. May 01 09:27:16 motivo systemd[1]: Started Daily man-db regeneration. May 01 09:27:16 motivo vtcs[2963]: STARTED TidalConnect services. May 01 09:27:16 motivo volumio[2647]: info: Discovery: adding 20adbe58-4037-4930-b39b-32543b6f5bc4 May 01 09:27:16 motivo volumio[2647]: info: Discovery: Found device Motivo May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:16 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:16 motivo volumio[2647]: info: MRS: Found cast device: S801B-ff0ab9b74701e208d26a65bc56f88ce3 May 01 09:27:16 motivo volumio[2647]: info: Discovery: adding c719d579-661a-4a58-b12a-d4b86c78aed0 May 01 09:27:16 motivo volumio[2647]: info: Discovery: Found device Integro May 01 09:27:16 motivo volumio[2647]: info: Discovery: Connecting to remote: 192.168.1.66 May 01 09:27:16 motivo volumio[2647]: info: Executing endpoint tc_connect May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 01 09:27:16 motivo volumio[2647]: info: Connecting to TidalConnect May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::servicePushState May 01 09:27:16 motivo volumio[2647]: info: CoreStateMachine::pushState May 01 09:27:16 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::volumioPushState May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing multiroomSync output update for this device May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing multiroomSync output May 01 09:27:16 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:16 motivo volumio[2647]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::servicePushState May 01 09:27:16 motivo volumio[2647]: info: CoreStateMachine::pushState May 01 09:27:16 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:16 motivo volumio[2647]: info: CoreCommandRouter::volumioPushState May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing multiroomSync output update for this device May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing multiroomSync output May 01 09:27:16 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:16 motivo volumio[2647]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect May 01 09:27:16 motivo volumio[2647]: info: Updating RAAT Signal Path May 01 09:27:16 motivo volumio[2647]: info: Updating RAAT Signal Path May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing audio output on request May 01 09:27:16 motivo volumio[2647]: info: MRS: Pushing multiroomSync output May 01 09:27:16 motivo volumio[2647]: info: Discovery: Connected to remote: 192.168.1.66 May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 09:27:17 motivo volumio[2647]: info: Received Get System Info May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:27:17 motivo volumio[2647]: info: Discovery: Getting this device information May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:17 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:27:17 motivo systemd[1]: apt-daily.service: Succeeded. May 01 09:27:17 motivo systemd[1]: Started Daily apt download activities. May 01 09:27:17 motivo systemd[1]: Starting Daily apt upgrade and clean activities... May 01 09:27:17 motivo volumio[2647]: ------------------------------------ BT MESSAGE: BT STATUS: running May 01 09:27:17 motivo volumio-remote-updater[580]: [2024-05-01 09:27:17] [connect] Successful connection May 01 09:27:17 motivo volumio-remote-updater[580]: [2024-05-01 09:27:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714555637 101 May 01 09:27:17 motivo volumio[2647]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9 May 01 09:27:17 motivo ntpd[742]: Soliciting pool server 149.62.187.162 May 01 09:27:17 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 09:27:17 motivo ntpd[742]: Soliciting pool server 185.19.184.35 May 01 09:27:17 motivo volumio[2647]: info: Successfully initialized Motivo I2S Bus May 01 09:27:17 motivo systemd[1]: apt-daily-upgrade.service: Succeeded. May 01 09:27:17 motivo systemd[1]: Started Daily apt upgrade and clean activities. May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 09:27:18 motivo volumio[2647]: info: Received Get System Info May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:27:18 motivo volumio[2647]: info: Discovery: Getting this device information May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:27:18 motivo volumio[2647]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 10 May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 01 09:27:18 motivo volumio[2647]: info: MRS: Getting audio outputs on start May 01 09:27:18 motivo volumio[2647]: info: MRS: Requesting all other devices output May 01 09:27:18 motivo volumio[2647]: info: MRS: Adding multiroomSync output May 01 09:27:18 motivo volumio[2647]: info: Adding audio output: May 01 09:27:18 motivo volumio[2647]: info: MRS: Pushing multiroomSync output May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetVisibleSources May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetQueue May 01 09:27:18 motivo volumio[2647]: info: CoreStateMachine::getQueue May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getQueue May 01 09:27:18 motivo volumio[2647]: info: Listing playlists May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 09:27:18 motivo volumio[2647]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 01 09:27:18 motivo volumio[2647]: info: Received Get System Info May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:27:18 motivo volumio[2647]: info: Discovery: Getting this device information May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:27:18 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:18 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:18 motivo ntpd[742]: Soliciting pool server 185.157.229.254 May 01 09:27:18 motivo ntpd[742]: Soliciting pool server 129.152.16.145 May 01 09:27:19 motivo volumio[2647]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 01 09:27:19 motivo volumio[2647]: info: TidalConnect service started! May 01 09:27:19 motivo volumio[2647]: info: Completed starting Core Plugins May 01 09:27:19 motivo volumio[2647]: info: ------------------------------------------- May 01 09:27:19 motivo volumio[2647]: info: ----- MyVolumio plugins startup ---- May 01 09:27:19 motivo volumio[2647]: info: ------------------------------------------- May 01 09:27:19 motivo volumio[2647]: info: [MyVolumio PluginManager] Fetching plans data.... May 01 09:27:19 motivo ntpd[742]: Soliciting pool server 162.159.200.123 May 01 09:27:20 motivo volumio[2933]: ...............................................++++ May 01 09:27:20 motivo ntpd[742]: Soliciting pool server 2a03:b020:0:404::50 May 01 09:27:22 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:22 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:22 motivo volumio[2933]: ......................++++ May 01 09:27:22 motivo volumio[2933]: e is 65537 (0x010001) May 01 09:27:22 motivo volumio[2933]: writing RSA key May 01 09:27:23 motivo volumio[2647]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 09:27:23 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 09:27:23 motivo volumio[2647]: info: Checking for updated MCU Firmware May 01 09:27:23 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 09:27:23 motivo volumio[2647]: info: Firware on device is on latest version, no need to update May 01 09:27:24 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 01 09:27:24 motivo sudo[3112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 01 09:27:24 motivo sudo[3112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3112]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 09:27:24 motivo sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 09:27:24 motivo sudo[3125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3118]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo sudo[3125]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo sudo[3132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 01 09:27:24 motivo sudo[3132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 09:27:24 motivo sudo[3137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3132]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo sudo[3137]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 01 09:27:24 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 01 09:27:24 motivo sudo[3141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 09:27:24 motivo sudo[3141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo sudo[3141]: pam_unix(sudo:session): session closed for user root May 01 09:27:24 motivo sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 09:27:24 motivo sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:24 motivo volumio[2647]: info: Executing endpoint metavolumio May 01 09:27:24 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 01 09:27:28 motivo volumio[2647]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 01 09:27:28 motivo sudo[3144]: pam_unix(sudo:session): session closed for user root May 01 09:27:29 motivo volumio[2647]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 01 09:27:29 motivo volumio[2647]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 01 09:27:29 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:29 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:29 motivo volumio[2647]: info: Starting MyVolumio Remote Streaming Endpoints May 01 09:27:29 motivo volumio[2647]: info: MyVolumio login type: Token May 01 09:27:29 motivo volumio[2647]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 01 09:27:29 motivo volumio[2647]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' May 01 09:27:29 motivo volumio[2647]: error: MyVolumio Custom Token format not valid, refreshing it May 01 09:27:30 motivo volumio[2647]: info: MyVolumio login type: Token May 01 09:27:30 motivo volumio[2647]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 01 09:27:30 motivo volumio[2647]: verbose: New Socket.io Connection to 192.168.1.164 from 192.168.1.34 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: 11 May 01 09:27:30 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:30 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:30 motivo volumio[2647]: info: CoreCommandRouter::volumioGetQueue May 01 09:27:30 motivo volumio[2647]: info: CoreStateMachine::getQueue May 01 09:27:30 motivo volumio[2647]: info: CorePlayQueue::getQueue May 01 09:27:30 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 01 09:27:30 motivo volumio[2647]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 01 09:27:30 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 01 09:27:30 motivo volumio[2647]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 01 09:27:30 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 01 09:27:31 motivo volumio[2647]: info: MyVolumio token set successfully May 01 09:27:31 motivo volumio[2647]: info: MYVOLUMIO: Adding device May 01 09:27:31 motivo volumio[2647]: info: MYVOLUMIO: Evaluating Server May 01 09:27:32 motivo volumio[2647]: info: MyVolumio Plan changed: superstar May 01 09:27:32 motivo volumio[2647]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar May 01 09:27:32 motivo volumio[2647]: info: Adding audio output: May 01 09:27:32 motivo volumio[2647]: info: MYVOLUMIO: Adding device May 01 09:27:32 motivo volumio[2647]: info: MYVOLUMIO: Evaluating Server May 01 09:27:32 motivo volumio[2647]: info: Remote config written successfully May 01 09:27:32 motivo volumio[2647]: info: Starting Tunnel 1 May 01 09:27:32 motivo volumio[2647]: info: Starting Tunnel Connection Checker May 01 09:27:32 motivo volumio[2647]: info: MYVolumio Device enabled May 01 09:27:32 motivo volumio[2647]: info: MyVolumio status changed May 01 09:27:32 motivo volumio[2647]: info: Streaming services startup May 01 09:27:32 motivo volumio[2647]: info: Starting Streaming Daemon May 01 09:27:32 motivo volumio[2647]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 01 09:27:32 motivo volumio[2647]: info: Setting Geolocation for MyVolumio to eu4 May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:32 motivo sudo[3180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 01 09:27:32 motivo sudo[3180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 01 09:27:32 motivo volumio[2647]: error: [MyVolumio PluginManager] Cache data is invalid! May 01 09:27:32 motivo sudo[3180]: pam_unix(sudo:session): session closed for user root May 01 09:27:32 motivo volumio[2647]: error: Cannot start Volumio Streaming Daemon May 01 09:27:32 motivo volumio[2647]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 01 09:27:32 motivo volumio[2647]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 01 09:27:32 motivo volumio[2647]: info: Setting Geolocation for MyVolumio to eu4 May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:32 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: Successfully Added MyVolumio device May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 01 09:27:33 motivo volumio[2647]: info: Successfully Added MyVolumio device May 01 09:27:33 motivo volumio[2647]: info: Updating MyVolumio device info May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: Updating MyVolumio device info May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:33 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:34 motivo volumio[2647]: info: Successfully Updated MyVolumio device May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 09:27:34 motivo volumio[2647]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 09:27:34 motivo volumio[2647]: info: Received Get System Version May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 09:27:34 motivo volumio[2647]: info: Received Get System Info May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 09:27:34 motivo volumio[2647]: info: Discovery: Getting this device information May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:34 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:34 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 09:27:34 motivo volumio[2647]: info: Successfully Updated MyVolumio device May 01 09:27:36 motivo volumio[2647]: info: CALLMETHOD: system_controller system sendBugReport [object Object] May 01 09:27:36 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport May 01 09:27:37 motivo sudo[3200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig May 01 09:27:37 motivo sudo[3200]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:37 motivo sudo[3200]: pam_unix(sudo:session): session closed for user root May 01 09:27:37 motivo sudo[3225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 01 09:27:37 motivo sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:37 motivo systemd[1]: Started MyVolumio SSH Tunnel. May 01 09:27:37 motivo sudo[3225]: pam_unix(sudo:session): session closed for user root May 01 09:27:37 motivo volumio[2647]: info: Remote SSH Started May 01 09:27:37 motivo autossh[3236]: port set to 0, monitoring disabled May 01 09:27:37 motivo autossh[3236]: starting ssh (count 1) May 01 09:27:37 motivo autossh[3236]: ssh child pid is 3241 May 01 09:27:37 motivo volumio[2647]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 May 01 09:27:37 motivo volumiossh-tunnel[3233]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. May 01 09:27:37 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:37 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:39 motivo volumio[2647]: error: MyVolumio Plugin failed to start in a timely fashion May 01 09:27:39 motivo volumio[2647]: info: BOOT COMPLETED May 01 09:27:39 motivo volumio[2647]: [Metrics] CommandRouter: 35s 192.43ms May 01 09:27:39 motivo volumio[2647]: info: CoreCommandRouter::volumiosetStartupVolume May 01 09:27:39 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 09:27:39 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:39 motivo volumio[2647]: info: CoreCommandRouter::Close All Modals sent May 01 09:27:39 motivo volumio[2647]: info: CoreCommandRouter::Close All Modals sent May 01 09:27:40 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 01 09:27:40 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 01 09:27:40 motivo volumio[2647]: No protocol specified May 01 09:27:40 motivo volumio[2647]: xcb_connection_has_error() returned true May 01 09:27:40 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 01 09:27:40 motivo sudo[3277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 May 01 09:27:40 motivo sudo[3277]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 09:27:41 motivo sudo[3277]: pam_unix(sudo:session): session closed for user root May 01 09:27:41 motivo volumio[2647]: info: Log sent successfully, reply: {"status":"OK","link":"http://logs.volumio.org/motivo/8p3Bmjf.html"} May 01 09:27:43 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 09:27:43 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 09:27:43 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 01 09:27:43 motivo volumio-remote-updater[580]: No test mode May 01 09:27:43 motivo volumio-remote-updater[580]: No alpha test mode May 01 09:27:43 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 01 09:27:43 motivo volumio[2647]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 01 09:27:43 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 01 09:27:44 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 09:27:44 motivo volumio[2647]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 01 09:27:44 motivo volumio[2647]: info: CoreCommandRouter::volumioGetState May 01 09:27:44 motivo volumio[2647]: info: CorePlayQueue::getTrack 0 May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::volumioGetBrowseSources May 01 09:27:49 motivo volumio[2647]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 09:28:14 motivo volumio[2647]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 09:28:14 motivo volumio[2647]: TypeError: self.getSpeakingHTTPAgentString is not a function May 01 09:28:14 motivo volumio[2647]: at Timeout.ControllerWebradio.fetchBBCRadiosList [as _onTimeout] (/volumio/app/plugins/music_service/webradio/index.js:1581:98) May 01 09:28:14 motivo volumio[2647]: at listOnTimeout (internal/timers.js:554:17) May 01 09:28:14 motivo volumio[2647]: at processTimers (internal/timers.js:497:7) May 01 09:28:14 motivo volumio[2647]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 09:28:14 motivo sudo[3374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 09:27 May 01 09:28:14 motivo sudo[3374]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 03:53:28 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="452d2369903b0ea4489e681db89bc2ea"