-- Logs begin at Mon 2024-07-08 21:40:19 HKT, end at Tue 2024-11-26 20:28:39 HKT. -- Nov 26 20:27:10 rw-pifi-43 ntpd[726]: receive: Unexpected origin timestamp 0xea366b13.a7af2708 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeaf03d1e.93c3ffd0 Nov 26 20:27:10 rw-pifi-43 ntpd[726]: receive: Unexpected origin timestamp 0xea366b13.a7b12c98 does not match aorg 0000000000.00000000 from server@117.80.112.205 xmt 0xeaf03d1e.98714112 Nov 26 20:27:10 rw-pifi-43 ntpd[726]: receive: Unexpected origin timestamp 0xea366b13.a7b2103e does not match aorg 0000000000.00000000 from server@202.112.29.82 xmt 0xeaf03d1e.9a398764 Nov 26 20:27:10 rw-pifi-43 systemd[1]: Starting Daily apt download activities... Nov 26 20:27:10 rw-pifi-43 ntpd[726]: receive: Unexpected origin timestamp 0xea366b13.a7ac86d1 does not match aorg 0000000000.00000000 from server@193.182.111.143 xmt 0xeaf03d1e.b2635f39 Nov 26 20:27:11 rw-pifi-43 systemd[1]: apt-daily.service: Succeeded. Nov 26 20:27:11 rw-pifi-43 systemd[1]: Started Daily apt download activities. Nov 26 20:27:11 rw-pifi-43 systemd[1]: Starting Daily apt upgrade and clean activities... Nov 26 20:27:11 rw-pifi-43 systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 26 20:27:11 rw-pifi-43 systemd[1]: Started Daily apt upgrade and clean activities. Nov 26 20:27:13 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: Discovery: A device disappeared from network Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: Discovery: Device rw-pifi-43 disappeared from network Nov 26 20:27:14 rw-pifi-43 sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 20:27:14 rw-pifi-43 sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: Discovery: adding 17b5eb19-09fc-47c9-91bc-5ee95e621d02 Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: Discovery: Found device RW-PiFi-43 Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:14 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:14 rw-pifi-43 sudo[1418]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:14 rw-pifi-43 sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 20:27:14 rw-pifi-43 sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:14 rw-pifi-43 sudo[1420]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:14 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to 10.27.10.143 from 10.27.10.97 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: Listing playlists Nov 26 20:27:15 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:17 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin bluetooth to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin multiroom to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin metavolumio to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin cd_controller to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: Starting MyVolumio Remote Streaming Endpoints Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: MyVolumio login type: Token Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 26 20:27:18 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: Starting Streaming Service Transparent Proxy Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: Streaming services startup Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: Starting Streaming Daemon Nov 26 20:27:19 rw-pifi-43 sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 26 20:27:19 rw-pifi-43 sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:19 rw-pifi-43 sudo[1432]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 26 20:27:19 rw-pifi-43 volumio[744]: error: Cannot start Volumio Streaming Daemon Nov 26 20:27:19 rw-pifi-43 volumio[744]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 26 20:27:19 rw-pifi-43 volumio[744]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 26 20:27:19 rw-pifi-43 volumio[744]: info: CoreCommandRouter::Close All Modals sent Nov 26 20:27:20 rw-pifi-43 volumio[744]: error: MyVolumio Custom Token format not valid, refreshing it Nov 26 20:27:20 rw-pifi-43 volumio[744]: STREAMING PROXY: Starting server on port 3245 Nov 26 20:27:20 rw-pifi-43 volumio[744]: Node JS runtime: 14 Nov 26 20:27:20 rw-pifi-43 sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 20:27:20 rw-pifi-43 sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:20 rw-pifi-43 sudo[1440]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:20 rw-pifi-43 sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 20:27:20 rw-pifi-43 sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:20 rw-pifi-43 sudo[1443]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:20 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to 10.27.10.143 from 10.27.10.97 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:20 rw-pifi-43 volumio[744]: info: Listing playlists Nov 26 20:27:21 rw-pifi-43 volumio[744]: info: MyVolumio login type: Token Nov 26 20:27:22 rw-pifi-43 sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 20:27:22 rw-pifi-43 sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:22 rw-pifi-43 sudo[1448]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:22 rw-pifi-43 sudo[1451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 20:27:22 rw-pifi-43 sudo[1451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 26 20:27:22 rw-pifi-43 sudo[1451]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:22 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to 10.27.10.143 from 10.27.10.97 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: Listing playlists Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: MyVolumio token set successfully Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: MYVOLUMIO: Adding device Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: MYVOLUMIO: Evaluating Server Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: Received Get System Info Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: Discovery: Getting this device information Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:22 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: MyVolumio Plan changed: premium Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Removing browser output: myVolumio user plan is not superstar Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Removing audio output: Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: MYVOLUMIO: Adding device Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: MYVOLUMIO: Evaluating Server Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Remote config written successfully Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Starting Tunnel 1 Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Starting Tunnel Connection Checker Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: MYVolumio Device enabled Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: MyVolumio status changed Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Streaming services startup Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Starting Streaming Daemon Nov 26 20:27:23 rw-pifi-43 sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 26 20:27:23 rw-pifi-43 sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:23 rw-pifi-43 sudo[1488]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:23 rw-pifi-43 volumio[744]: error: Cannot start Volumio Streaming Daemon Nov 26 20:27:23 rw-pifi-43 volumio[744]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 26 20:27:23 rw-pifi-43 volumio[744]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 26 20:27:23 rw-pifi-43 volumio[744]: error: [MyVolumio PluginManager] Cache data is invalid! Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Setting Geolocation for MyVolumio to au1 Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: Setting Geolocation for MyVolumio to au1 Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:24 rw-pifi-43 volumio[744]: info: Successfully Added MyVolumio device Nov 26 20:27:24 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 26 20:27:24 rw-pifi-43 volumio[744]: info: [1732624044274] Starting BluetoothController Nov 26 20:27:24 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 26 20:27:25 rw-pifi-43 sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 26 20:27:25 rw-pifi-43 sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 sudo[1493]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: MultiRoom plugin initialized Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: STOPPING SNAPCLIENT Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Snap server stop Nov 26 20:27:25 rw-pifi-43 sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 26 20:27:25 rw-pifi-43 sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: STOPPING volumioStreaming Nov 26 20:27:25 rw-pifi-43 sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 26 20:27:25 rw-pifi-43 sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 sudo[1509]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 26 20:27:25 rw-pifi-43 sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 26 20:27:25 rw-pifi-43 sudo[1512]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 26 20:27:25 rw-pifi-43 sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 sudo[1518]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 sudo[1515]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Preparing to generate the ALSA configuration file Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Updating MyVolumio device info Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Updating MyVolumio device info Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Reading ALSA contributions from plugins. Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Successfully Added MyVolumio device Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Removed streaming files Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: volumioStreaming STOPPED Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: SNAPSERVER STOPPED Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: SNAPCLIENT STOPPED Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Asound.conf file written Nov 26 20:27:25 rw-pifi-43 sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 26 20:27:25 rw-pifi-43 sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 sudo[1521]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Output device has changed, restarting MPD Nov 26 20:27:25 rw-pifi-43 sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 20:27:25 rw-pifi-43 sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 sudo[1526]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Output device has changed, restarting Shairport Sync Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:25 rw-pifi-43 sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 20:27:25 rw-pifi-43 sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 systemd[1]: Stopping Music Player Daemon... Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 20:27:25 rw-pifi-43 volumio[744]: [SpotifyConnect] Creating VLS config file Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output for this device Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:25 rw-pifi-43 sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name RW-PiFi-43 Nov 26 20:27:25 rw-pifi-43 sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:25 rw-pifi-43 systemd[1]: mpd.service: Succeeded. Nov 26 20:27:25 rw-pifi-43 systemd[1]: Stopped Music Player Daemon. Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 26 20:27:25 rw-pifi-43 volumio[744]: info: Adding METAVOLUMIO REST API Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding Manifest REST API Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Preparing CD Folders Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding CD REST API Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Starting UDEV Watcher for CD Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Detecting CD presence with UDEV Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 26 20:27:26 rw-pifi-43 systemd[1]: Starting Music Player Daemon... Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding inputs REST Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Scanning Audio Inputs Nov 26 20:27:26 rw-pifi-43 sudo[1537]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 kernel: Bluetooth: Core ver 2.22 Nov 26 20:27:26 rw-pifi-43 kernel: NET: Registered PF_BLUETOOTH protocol family Nov 26 20:27:26 rw-pifi-43 kernel: Bluetooth: HCI device and connection manager initialized Nov 26 20:27:26 rw-pifi-43 kernel: Bluetooth: HCI socket layer initialized Nov 26 20:27:26 rw-pifi-43 kernel: Bluetooth: L2CAP socket layer initialized Nov 26 20:27:26 rw-pifi-43 kernel: Bluetooth: SCO socket layer initialized Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Checking against Known Cards name Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding Server instance for streaming Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 26 20:27:26 rw-pifi-43 volumio[744]: error: Hi Res Audio Failed Login: Missing Login Data Nov 26 20:27:26 rw-pifi-43 sudo[1542]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 20:27:26 rw-pifi-43 sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Refreshing TIDAL token Nov 26 20:27:26 rw-pifi-43 sudo[1542]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 26 20:27:26 rw-pifi-43 sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 26 20:27:26 rw-pifi-43 sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Stopping AccessToken refresher cron for QOBUZ Nov 26 20:27:26 rw-pifi-43 sudo[1554]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: AccessToken refresher cron started for QOBUZ Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding QOBUZ REST API Endpoints Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 26 20:27:26 rw-pifi-43 volumio[744]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 26 20:27:26 rw-pifi-43 sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Nov 26 20:27:26 rw-pifi-43 sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:26 rw-pifi-43 volumio[744]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'RW-PiFi-43' Nov 26 20:27:26 rw-pifi-43 volumio[744]: Can't get device info: No such device Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: MPD Permissions set Nov 26 20:27:26 rw-pifi-43 systemd[1]: Stopping Volspotconnect2 Daemon... Nov 26 20:27:26 rw-pifi-43 systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Nov 26 20:27:26 rw-pifi-43 systemd[1]: volspotconnect.service: Succeeded. Nov 26 20:27:26 rw-pifi-43 systemd[1]: Stopped Volspotconnect2 Daemon. Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:26 rw-pifi-43 systemd[1]: Started Volspotconnect2 Daemon. Nov 26 20:27:26 rw-pifi-43 volumio[1560]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Nov 26 20:27:26 rw-pifi-43 volumio[1560]: Reading Config from "/tmp/volspotify.toml" Nov 26 20:27:26 rw-pifi-43 volumio[1560]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 26 20:27:26 rw-pifi-43 sudo[1558]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 volumio[744]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Starting Shairport Sync Nov 26 20:27:26 rw-pifi-43 volumio[1560]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 26 20:27:26 rw-pifi-43 sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 20:27:26 rw-pifi-43 sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:26 rw-pifi-43 volumio[1560]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Nov 26 20:27:26 rw-pifi-43 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 20:27:26 rw-pifi-43 systemd[1]: shairport-sync.service: Succeeded. Nov 26 20:27:26 rw-pifi-43 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 20:27:26 rw-pifi-43 volumio[1560]: [Vollibrespot] : Could not connect to server: Connection refused (os error 111) Nov 26 20:27:26 rw-pifi-43 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 20:27:26 rw-pifi-43 sudo[1572]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Shairport-Sync Started Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Successfully Updated MyVolumio device Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Successfully Updated MyVolumio device Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 volumio[744]: info: Adding audio output: Nov 26 20:27:26 rw-pifi-43 mpd[1552]: Nov 26 20:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 20:27:26 rw-pifi-43 systemd[1]: Started Music Player Daemon. Nov 26 20:27:26 rw-pifi-43 sudo[1529]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:26 rw-pifi-43 volumio[744]: error: updateQueue error: null Nov 26 20:27:27 rw-pifi-43 volumio[744]: info: Access Token successfully retrieved Nov 26 20:27:27 rw-pifi-43 volumio[744]: info: Successfully retrieved User Session From TIDAL Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Successfully retrieved User Subscription From TIDAL Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Adding TIDAL to Browse Sources Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: [1732624048166] CoreMusicLibrary::Adding element TIDAL Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:28 rw-pifi-43 volumio[744]: Cannot find translation for source TIDAL Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Stopping AccessToken refresher cron Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: AccessToken refresher cron started Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Adding TIDAL REST API Endpoints Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 26 20:27:28 rw-pifi-43 sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 26 20:27:28 rw-pifi-43 sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:28 rw-pifi-43 systemd[1]: Started MyVolumio SSH Tunnel. Nov 26 20:27:28 rw-pifi-43 sudo[1584]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: Remote SSH Started Nov 26 20:27:28 rw-pifi-43 autossh[1587]: port set to 0, monitoring disabled Nov 26 20:27:28 rw-pifi-43 autossh[1587]: starting ssh (count 1) Nov 26 20:27:28 rw-pifi-43 autossh[1587]: ssh child pid is 1590 Nov 26 20:27:28 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:28 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:28 rw-pifi-43 sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 26 20:27:28 rw-pifi-43 sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:29 rw-pifi-43 systemd[1]: Started Volumio Bluetooth Module. Nov 26 20:27:29 rw-pifi-43 sudo[1592]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:29 rw-pifi-43 sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 26 20:27:29 rw-pifi-43 sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:29 rw-pifi-43 volumiobt[1594]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 26 20:27:29 rw-pifi-43 sudo[1595]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:29 rw-pifi-43 volumiossh-tunnel[1586]: Warning: Permanently added '[au1.myvolumio.org]:2222,[170.64.163.26]:2222' (RSA) to the list of known hosts. Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Volumio BT Module successfully started Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: TidalConnect service stoped! Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 26 20:27:29 rw-pifi-43 sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 26 20:27:29 rw-pifi-43 sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:29 rw-pifi-43 systemd[1]: Started Volumio Tidal Connect Service. Nov 26 20:27:29 rw-pifi-43 sudo[1607]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Executing endpoint tc_getconfig Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 26 20:27:29 rw-pifi-43 vtcs[1609]: STARTING TidalConnect services, version: 1.4.0.34 Nov 26 20:27:29 rw-pifi-43 vtcs[1609]: STARTED TidalConnect services. Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Executing endpoint tc_connect Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: Connecting to TidalConnect Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::servicePushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 26 20:27:29 rw-pifi-43 volumio[744]: verbose: CURRENT POSITION 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::syncState stateService stop Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::syncState currentStatus stop Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: No code Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::servicePushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 26 20:27:29 rw-pifi-43 volumio[744]: verbose: CURRENT POSITION 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::syncState stateService stop Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::syncState currentStatus stop Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: No code Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreStateMachine::pushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioPushState Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output update for this device Nov 26 20:27:29 rw-pifi-43 volumio[744]: info: MRS: Pushing multiroomSync output Nov 26 20:27:30 rw-pifi-43 sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 26 20:27:30 rw-pifi-43 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: pulseaudio: no process found Nov 26 20:27:30 rw-pifi-43 sudo[1628]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1632 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 26 20:27:30 rw-pifi-43 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 26 20:27:30 rw-pifi-43 volumio[744]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 26 20:27:30 rw-pifi-43 systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Successfully called chroot. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Successfully dropped privileges. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Successfully limited resources. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Running. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Canary thread running. Nov 26 20:27:30 rw-pifi-43 rtkit-daemon[1633]: Watchdog thread running. Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1633 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 26 20:27:30 rw-pifi-43 systemd[1]: Starting Authorization Manager... Nov 26 20:27:30 rw-pifi-43 volumio[744]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 20:27:30 rw-pifi-43 polkitd[1636]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 26 20:27:30 rw-pifi-43 systemd[1]: Started Authorization Manager. Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.37' (uid=1000 pid=1632 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 26 20:27:30 rw-pifi-43 pulseaudio[1632]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 26 20:27:30 rw-pifi-43 pulseaudio[1632]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Applying permissions Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Setting BT discoverable and pairable Nov 26 20:27:30 rw-pifi-43 systemd[1]: Starting Bluetooth service... Nov 26 20:27:30 rw-pifi-43 volumio[744]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 20:27:30 rw-pifi-43 bluetoothd[1641]: Bluetooth daemon 5.50 Nov 26 20:27:30 rw-pifi-43 bluetoothd[1641]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Successfully activated service 'org.bluez' Nov 26 20:27:30 rw-pifi-43 systemd[1]: Started Bluetooth service. Nov 26 20:27:30 rw-pifi-43 bluetoothd[1641]: Starting SDP server Nov 26 20:27:30 rw-pifi-43 bluetoothd[1641]: Excluding (cli) sap Nov 26 20:27:30 rw-pifi-43 bluetoothd[1641]: Bluetooth management interface 1.22 initialized Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.39' (uid=0 pid=1641 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# -e power on Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Invalid command in menu main: -e Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Use "help" for a list of available commands in a menu. Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Use "menu " if you want to enter any submenu. Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Use "back" if you want to return to menu main. Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# agent on Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Failed to register agent object Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# discoverable on Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: No default controller available Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# pairable on Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: No default controller available Nov 26 20:27:30 rw-pifi-43 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 26 20:27:30 rw-pifi-43 kernel: Bluetooth: BNEP filters: protocol multicast Nov 26 20:27:30 rw-pifi-43 kernel: Bluetooth: BNEP socket layer initialized Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# agent NoInputNoOutput Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: Failed to register agent object Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# default-agent Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: No agent is registered Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [bluetooth]# quit Nov 26 20:27:30 rw-pifi-43 pulseaudio[1632]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 26 20:27:30 rw-pifi-43 volumiobt[1594]: [59B blob data] Nov 26 20:27:30 rw-pifi-43 systemd[1]: Starting Hostname Service... Nov 26 20:27:30 rw-pifi-43 volumio[744]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 20:27:30 rw-pifi-43 dbus-daemon[534]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 26 20:27:30 rw-pifi-43 systemd[1]: Started Hostname Service. Nov 26 20:27:30 rw-pifi-43 volumio[744]: info: MRS: Getting audio outputs on start Nov 26 20:27:30 rw-pifi-43 volumio[744]: info: MRS: Requesting all other devices output Nov 26 20:27:31 rw-pifi-43 sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 20:27:31 rw-pifi-43 sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:31 rw-pifi-43 sudo[1649]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:31 rw-pifi-43 sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 20:27:31 rw-pifi-43 sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:31 rw-pifi-43 sudo[1652]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:31 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to 10.27.10.143 from 10.27.10.97 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:31 rw-pifi-43 volumio[744]: info: Listing playlists Nov 26 20:27:32 rw-pifi-43 volumio[744]: info: Checking Spotify Web API Nov 26 20:27:32 rw-pifi-43 volumio[744]: info: TidalConnect service started! Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: Received Get System Info Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: Discovery: Getting this device information Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 20:27:33 rw-pifi-43 sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 20:27:33 rw-pifi-43 sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:33 rw-pifi-43 sudo[1657]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:33 rw-pifi-43 sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 20:27:33 rw-pifi-43 sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:33 rw-pifi-43 sudo[1660]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:33 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to 10.27.10.143 from 10.27.10.97 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: Listing playlists Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 20:27:33 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetState Nov 26 20:27:33 rw-pifi-43 volumio[744]: info: CorePlayQueue::getTrack 0 Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 20:27:38 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 20:27:51 rw-pifi-43 volumio[744]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/armhf Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Systeminfo/volumio/buster/armhf Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Check plugin dependencies Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Rename folder Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Folder /tmp/downloaded_plugin.zip removed Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Move to category Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Checking if install.sh is present Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Executing install.sh Nov 26 20:27:53 rw-pifi-43 sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/Systeminfo/install.sh Nov 26 20:27:53 rw-pifi-43 sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Installing systeminfo Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Configuration File exists removing it Nov 26 20:27:53 rw-pifi-43 sudo[1683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /data/configuration/user_interface/Systeminfo/config.json Nov 26 20:27:53 rw-pifi-43 sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:53 rw-pifi-43 sudo[1683]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Detected cpu architecture as armv7l Nov 26 20:27:53 rw-pifi-43 sudo[1688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/user_interface/Systeminfo/c/hw_params_arm /data/plugins/user_interface/Systeminfo/hw_params Nov 26 20:27:53 rw-pifi-43 sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:53 rw-pifi-43 sudo[1688]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:53 rw-pifi-43 sudo[1690]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/hw_params Nov 26 20:27:53 rw-pifi-43 sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:53 rw-pifi-43 sudo[1690]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:53 rw-pifi-43 sudo[1692]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/user_interface/Systeminfo/firmware.sh Nov 26 20:27:53 rw-pifi-43 sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:27:53 rw-pifi-43 sudo[1692]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:53 rw-pifi-43 sudo[1681]: pam_unix(sudo:session): session closed for user root Nov 26 20:27:53 rw-pifi-43 volumio[744]: Plugin install end detected on script Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Install script completed Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Done installing plugin. Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Enabling plugin Systeminfo Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"3.0.6","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":false}] Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Folder /tmp/plugins removed Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Folder /tmp/downloaded_plugin.zip removed Nov 26 20:27:53 rw-pifi-43 volumio[744]: info: Folder /data/temp removed Nov 26 20:28:00 rw-pifi-43 systemd[1]: systemd-hostnamed.service: Succeeded. Nov 26 20:28:08 rw-pifi-43 volumio[744]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:28:10 rw-pifi-43 volumio[744]: Cannot find translation for source TIDAL Nov 26 20:28:10 rw-pifi-43 volumio[744]: [SpotifyConnect] Closing metadata listener Nov 26 20:28:10 rw-pifi-43 sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Nov 26 20:28:10 rw-pifi-43 sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:28:10 rw-pifi-43 systemd[1]: Stopping Volspotconnect2 Daemon... Nov 26 20:28:10 rw-pifi-43 systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Nov 26 20:28:10 rw-pifi-43 systemd[1]: volspotconnect.service: Succeeded. Nov 26 20:28:10 rw-pifi-43 systemd[1]: Stopped Volspotconnect2 Daemon. Nov 26 20:28:10 rw-pifi-43 sudo[1741]: pam_unix(sudo:session): session closed for user root Nov 26 20:28:10 rw-pifi-43 volumio[744]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 26 20:28:10 rw-pifi-43 volumio[744]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: Check plugin dependencies Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: Rename folder Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: Folder /tmp/downloaded_plugin.zip removed Nov 26 20:28:10 rw-pifi-43 volumio[744]: info: Move to category Nov 26 20:28:12 rw-pifi-43 volumio[744]: info: Checking if install.sh is present Nov 26 20:28:12 rw-pifi-43 volumio[744]: info: Executing install.sh Nov 26 20:28:12 rw-pifi-43 sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Nov 26 20:28:12 rw-pifi-43 sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:28:12 rw-pifi-43 volumio[744]: info: Installing Go-librespot Nov 26 20:28:12 rw-pifi-43 volumio[744]: info: Checking old vollibrespot installs Nov 26 20:28:12 rw-pifi-43 systemd[1]: Reloading. Nov 26 20:28:12 rw-pifi-43 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 26 20:28:12 rw-pifi-43 systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 26 20:28:12 rw-pifi-43 systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 26 20:28:13 rw-pifi-43 systemd[1]: Reloading. Nov 26 20:28:13 rw-pifi-43 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 26 20:28:13 rw-pifi-43 volumio[744]: info: Clearing old vollibrespot Nov 26 20:28:13 rw-pifi-43 volumio[744]: info: vollibrespot cleared Nov 26 20:28:13 rw-pifi-43 volumio[744]: info: Dowloading daemon Nov 26 20:28:15 rw-pifi-43 volumio[744]: info: Creating Start Script Nov 26 20:28:15 rw-pifi-43 systemd[1]: Reloading. Nov 26 20:28:15 rw-pifi-43 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 26 20:28:16 rw-pifi-43 sudo[1755]: pam_unix(sudo:session): session closed for user root Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Install script completed Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Done installing plugin. Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Enabling plugin spop Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":false}] Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 20:28:16 rw-pifi-43 volumio[744]: Plugin install end detected on script Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Folder /tmp/plugins removed Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Folder /tmp/downloaded_plugin.zip removed Nov 26 20:28:16 rw-pifi-43 volumio[744]: info: Folder /data/temp removed Nov 26 20:28:23 rw-pifi-43 volumio[744]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 20:28:23 rw-pifi-43 volumio[744]: error: Cannot populate Spotify configuration: TypeError: Cannot read property 'bars' of undefined Nov 26 20:28:32 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 20:28:32 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 20:28:34 rw-pifi-43 volumio[744]: info: Enabling plugin spop Nov 26 20:28:34 rw-pifi-43 volumio[744]: info: Loading plugin "spop"... Nov 26 20:28:34 rw-pifi-43 volumio[744]: info: PLUGIN START: spop Nov 26 20:28:34 rw-pifi-43 volumio[744]: info: Checking for old spotify connect plugin installed Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 20:28:35 rw-pifi-43 volumio[744]: [SpotifyConnect] Creating VLS config file Nov 26 20:28:35 rw-pifi-43 volumio[744]: error: Failed to read template file: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/spop/volspotify.tmpl' Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 20:28:35 rw-pifi-43 volumio[744]: [SpotifyConnect] Starting metadata listener Nov 26 20:28:35 rw-pifi-43 volumio[744]: (node:744) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'replace' of undefined Nov 26 20:28:35 rw-pifi-43 volumio[744]: at ControllerSpotify.createConfigFile (/data/plugins/music_service/spop/index.js:2578:27) Nov 26 20:28:35 rw-pifi-43 volumio[744]: at ControllerSpotify.init (/data/plugins/music_service/spop/index.js:2441:14) Nov 26 20:28:35 rw-pifi-43 volumio[744]: at Promise._successFn (/data/plugins/music_service/spop/index.js:133:14) Nov 26 20:28:35 rw-pifi-43 volumio[744]: at nextTickCallback (/data/plugins/music_service/spop/node_modules/kew/kew.js:47:28) Nov 26 20:28:35 rw-pifi-43 volumio[744]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 26 20:28:35 rw-pifi-43 volumio[744]: (Use `node --trace-warnings ...` to show where the warning was created) Nov 26 20:28:35 rw-pifi-43 volumio[744]: (node:744) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Nov 26 20:28:35 rw-pifi-43 volumio[744]: (node:744) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Nov 26 20:28:35 rw-pifi-43 sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Nov 26 20:28:35 rw-pifi-43 sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:28:35 rw-pifi-43 volumio[744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 26 20:28:35 rw-pifi-43 systemd[1]: Started Volspotconnect2 Daemon. Nov 26 20:28:35 rw-pifi-43 sudo[1817]: pam_unix(sudo:session): session closed for user root Nov 26 20:28:35 rw-pifi-43 volumio[1819]: /usr/lib/startconnect.sh: line 2: ./usr/bin/vollibrespot: No such file or directory Nov 26 20:28:35 rw-pifi-43 volumio[744]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 26 20:28:35 rw-pifi-43 volumio[744]: [Metrics] SpotifyConnect: 0s 116.04ms Nov 26 20:28:35 rw-pifi-43 volumio[744]: info: Done. Nov 26 20:28:35 rw-pifi-43 systemd[1]: volspotconnect.service: Main process exited, code=exited, status=127/n/a Nov 26 20:28:35 rw-pifi-43 systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Nov 26 20:28:36 rw-pifi-43 volumio[744]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 26 20:28:36 rw-pifi-43 volumio[744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 20:28:36 rw-pifi-43 volumio[744]: Cannot find translation for source TIDAL Nov 26 20:28:36 rw-pifi-43 volumio[744]: [SpotifyConnect] Closing metadata listener Nov 26 20:28:36 rw-pifi-43 volumio[744]: info: Disabling plugin spop Nov 26 20:28:36 rw-pifi-43 volumio[744]: info: Done. Nov 26 20:28:36 rw-pifi-43 sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Nov 26 20:28:36 rw-pifi-43 sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 20:28:36 rw-pifi-43 systemd[1]: Stopped Volspotconnect2 Daemon. Nov 26 20:28:36 rw-pifi-43 sudo[1822]: pam_unix(sudo:session): session closed for user root Nov 26 20:28:36 rw-pifi-43 volumio[744]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 26 20:28:39 rw-pifi-43 volumio[744]: info: Checking Spotify Web API Nov 26 20:28:39 rw-pifi-43 volumio[744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 20:28:39 rw-pifi-43 volumio[744]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Nov 26 20:28:39 rw-pifi-43 volumio[744]: at healthCheck (dgram.js:897:11) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at Socket.send (dgram.js:622:3) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2354:23) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2446:18) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at listOnTimeout (internal/timers.js:554:17) Nov 26 20:28:39 rw-pifi-43 volumio[744]: at processTimers (internal/timers.js:497:7) { Nov 26 20:28:39 rw-pifi-43 volumio[744]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Nov 26 20:28:39 rw-pifi-43 volumio[744]: } Nov 26 20:28:39 rw-pifi-43 volumio[744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 20:28:39 rw-pifi-43 sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 20:27 Nov 26 20:28:39 rw-pifi-43 sudo[1833]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"