-- 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"