-- Logs begin at Thu 2019-02-14 12:12:01 EET, end at Thu 2024-08-22 20:40:31 EEST. -- Aug 22 20:39:04 volumio systemd[1]: Starting Daily apt download activities... Aug 22 20:39:04 volumio systemd[1]: apt-daily.service: Succeeded. Aug 22 20:39:04 volumio systemd[1]: Started Daily apt download activities. Aug 22 20:39:04 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Aug 22 20:39:05 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 22 20:39:05 volumio systemd[1]: Started Daily apt upgrade and clean activities. Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin bluetooth to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin multiroom to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin metavolumio to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin cd_controller to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 22 20:39:07 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 22 20:39:08 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:08 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:08 volumio volumio[905]: info: Starting MyVolumio Remote Streaming Endpoints Aug 22 20:39:08 volumio volumio[905]: info: MyVolumio not started Aug 22 20:39:08 volumio volumio[905]: info: Initializing device activation check Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 22 20:39:08 volumio volumio[905]: info: Starting Streaming Service Transparent Proxy Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 22 20:39:08 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 22 20:39:08 volumio volumio[905]: info: Streaming services startup Aug 22 20:39:09 volumio volumio[905]: info: Starting Streaming Daemon Aug 22 20:39:09 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 22 20:39:09 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:09 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:09 volumio volumio[905]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 22 20:39:09 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 20:39:09 volumio volumio[905]: error: Cannot start Volumio Streaming Daemon Aug 22 20:39:09 volumio volumio[905]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 22 20:39:09 volumio volumio[905]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 22 20:39:09 volumio volumio[905]: STREAMING PROXY: Starting server on port 3245 Aug 22 20:39:09 volumio volumio[905]: Node JS runtime: 14 Aug 22 20:39:10 volumio volumio[905]: info: Discovery: A device disappeared from network Aug 22 20:39:10 volumio volumio[905]: info: Discovery: Device volumio disappeared from network Aug 22 20:39:10 volumio volumio[905]: info: Discovery: adding 6c3020a5-1575-432e-9a86-d560b7beff22 Aug 22 20:39:10 volumio volumio[905]: info: Discovery: Found device Volumio Aug 22 20:39:10 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:10 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:11 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 20:39:15 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 22 20:39:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:17 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:17 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:17 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:20 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 20:39:21 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 22 20:39:21 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:21 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:21 volumio sudo[2131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 22 20:39:21 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:21 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:21 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 22 20:39:21 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 22 20:39:21 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:21 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:21 volumio sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 22 20:39:21 volumio sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:21 volumio sudo[2137]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:21 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:21 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:39:21 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:21 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:21 volumio volumio[905]: info: Listing playlists Aug 22 20:39:21 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:39:21 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:39:21 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:39:22 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:39:22 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:22 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:39:22 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:22 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:22 volumio volumio[905]: info: Listing playlists Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:39:22 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:39:22 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:39:22 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 22 20:39:23 volumio volumio[905]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 22 20:39:23 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:23 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:23 volumio sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 22 20:39:23 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:23 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:23 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 22 20:39:23 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:23 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:39:23 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:23 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: Listing playlists Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:39:23 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:39:23 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:23 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:23 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:39:23 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 20:39:24 volumio volumio[905]: info: Received Get System Info Aug 22 20:39:24 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:24 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:24 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:24 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:24 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:24 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:24 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 22 20:39:24 volumio volumio[905]: info: MyVolumio login type: Token Aug 22 20:39:24 volumio volumio[905]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::volumioGetBrowseSources Aug 22 20:39:29 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 22 20:39:30 volumio volumio[905]: info: MyVolumio token set successfully Aug 22 20:39:30 volumio volumio[905]: info: MYVOLUMIO: Adding device Aug 22 20:39:30 volumio volumio[905]: info: MYVOLUMIO: Evaluating Server Aug 22 20:39:30 volumio volumio[905]: info: MyVolumio Plan changed: premium Aug 22 20:39:30 volumio volumio[905]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 22 20:39:30 volumio volumio[905]: info: Removing browser output: myVolumio user plan is not superstar Aug 22 20:39:30 volumio volumio[905]: info: Removing audio output: Aug 22 20:39:30 volumio volumio[905]: info: MYVOLUMIO: Adding device Aug 22 20:39:30 volumio volumio[905]: info: MYVOLUMIO: Evaluating Server Aug 22 20:39:30 volumio volumio[905]: info: Remote config written successfully Aug 22 20:39:30 volumio volumio[905]: info: Starting Tunnel 1 Aug 22 20:39:30 volumio volumio[905]: info: Starting Tunnel Connection Checker Aug 22 20:39:30 volumio volumio[905]: info: MYVolumio Device enabled Aug 22 20:39:30 volumio volumio[905]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 22 20:39:30 volumio volumio[905]: info: MyVolumio status changed Aug 22 20:39:30 volumio volumio[905]: info: Streaming services startup Aug 22 20:39:30 volumio volumio[905]: info: Starting Streaming Daemon Aug 22 20:39:31 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 22 20:39:31 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:31 volumio volumio[905]: info: Setting Geolocation for MyVolumio to eu10 Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:31 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:31 volumio volumio[905]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Aug 22 20:39:31 volumio volumio[905]: error: Cannot start Volumio Streaming Daemon Aug 22 20:39:31 volumio volumio[905]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 22 20:39:31 volumio volumio[905]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 22 20:39:31 volumio volumio[905]: info: Setting Geolocation for MyVolumio to eu7 Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 22 20:39:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 22 20:39:31 volumio volumio[905]: info: Successfully Added MyVolumio device Aug 22 20:39:31 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 22 20:39:32 volumio volumio[905]: info: [1724348372141] Starting BluetoothController Aug 22 20:39:32 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 22 20:39:33 volumio sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 22 20:39:33 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio volumio[905]: info: MRS: MultiRoom plugin initialized Aug 22 20:39:33 volumio volumio[905]: info: MRS: STOPPING SNAPCLIENT Aug 22 20:39:33 volumio volumio[905]: info: MRS: Snap server stop Aug 22 20:39:33 volumio sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 22 20:39:33 volumio sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio volumio[905]: info: MRS: STOPPING volumioStreaming Aug 22 20:39:33 volumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 22 20:39:33 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio sudo[2204]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 22 20:39:33 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 22 20:39:33 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 22 20:39:33 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 22 20:39:33 volumio volumio[905]: info: Preparing to generate the ALSA configuration file Aug 22 20:39:33 volumio volumio[905]: info: Updating MyVolumio device info Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: Updating MyVolumio device info Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 22 20:39:33 volumio volumio[905]: info: Successfully Added MyVolumio device Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 22 20:39:33 volumio volumio[905]: info: MRS: Removed streaming files Aug 22 20:39:33 volumio volumio[905]: info: MRS: volumioStreaming STOPPED Aug 22 20:39:33 volumio volumio[905]: info: MRS: SNAPSERVER STOPPED Aug 22 20:39:33 volumio volumio[905]: info: MRS: SNAPCLIENT STOPPED Aug 22 20:39:33 volumio volumio[905]: info: Asound.conf file unchanged, so no further update is needed Aug 22 20:39:33 volumio volumio[905]: info: Output device has changed, restarting MPD Aug 22 20:39:33 volumio sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 22 20:39:33 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio volumio[905]: info: Output device has changed, restarting Shairport Sync Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:33 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio sudo[2219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 22 20:39:33 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio systemd[1]: Stopping Music Player Daemon... Aug 22 20:39:33 volumio volumio[905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 22 20:39:33 volumio volumio[905]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:33 volumio systemd[1]: mpd.service: Succeeded. Aug 22 20:39:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 22 20:39:33 volumio volumio[905]: info: MRS: Pushing multiroomSync output for this device Aug 22 20:39:33 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:33 volumio volumio[905]: info: Adding audio output: Aug 22 20:39:33 volumio volumio[905]: info: Adding audio output: Aug 22 20:39:33 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Aug 22 20:39:33 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:33 volumio volumio[905]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 22 20:39:33 volumio volumio[905]: info: Adding METAVOLUMIO REST API Endpoints Aug 22 20:39:33 volumio volumio[905]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 22 20:39:33 volumio volumio[905]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 22 20:39:33 volumio volumio[905]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 22 20:39:33 volumio volumio[905]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 22 20:39:33 volumio volumio[905]: info: Preparing CD Folders Aug 22 20:39:33 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:33 volumio volumio[905]: info: Adding CD REST API Endpoints Aug 22 20:39:33 volumio volumio[905]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 22 20:39:33 volumio volumio[905]: info: Starting UDEV Watcher for CD Aug 22 20:39:33 volumio systemd[1]: Starting Music Player Daemon... Aug 22 20:39:33 volumio volumio[905]: info: Detecting CD presence with UDEV Aug 22 20:39:33 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 22 20:39:33 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 22 20:39:33 volumio volumio[905]: info: Adding inputs REST Endpoints Aug 22 20:39:33 volumio volumio[905]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 22 20:39:33 volumio volumio[905]: info: Scanning Audio Inputs Aug 22 20:39:33 volumio sudo[2224]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 22 20:39:33 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: Checking against Known Cards name Aug 22 20:39:34 volumio volumio[905]: info: Adding Server instance for streaming Aug 22 20:39:34 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 22 20:39:34 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio volumio[905]: error: Hi Res Audio Failed Login: Missing Login Data Aug 22 20:39:34 volumio volumio[905]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 22 20:39:34 volumio volumio[905]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 22 20:39:34 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 22 20:39:34 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 22 20:39:34 volumio volumio[905]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 22 20:39:34 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 22 20:39:34 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: Stopping AccessToken refresher cron Aug 22 20:39:34 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio volumio[905]: info: AccessToken refresher cron started Aug 22 20:39:34 volumio volumio[905]: info: Stopping AccessToken refresher cron for QOBUZ Aug 22 20:39:34 volumio volumio[905]: info: AccessToken refresher cron started for QOBUZ Aug 22 20:39:34 volumio volumio[905]: info: Adding TIDAL REST API Endpoints Aug 22 20:39:34 volumio volumio[905]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Aug 22 20:39:34 volumio volumio[905]: info: Adding QOBUZ REST API Endpoints Aug 22 20:39:34 volumio volumio[905]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 22 20:39:34 volumio volumio[905]: info: Bluetooth name changed to Volumio Aug 22 20:39:34 volumio volumio[905]: info: MPD Permissions set Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: Starting Shairport Sync Aug 22 20:39:34 volumio volumio[905]: info: MRS: Found cast device: MIBOX4-4193acff07fa12fe0117f4165650bfa1 Aug 22 20:39:34 volumio volumio[905]: info: Adding audio output: Aug 22 20:39:34 volumio sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 22 20:39:34 volumio volumio[905]: info: Preparing to generate the ALSA configuration file Aug 22 20:39:34 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: MRS: Found cast device: Google-Nest-Hub-86b77e2a68d4babc667ccd50161f084d Aug 22 20:39:34 volumio volumio[905]: info: Adding audio output: Aug 22 20:39:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 22 20:39:34 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 22 20:39:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio volumio[905]: info: Asound.conf file unchanged, so no further update is needed Aug 22 20:39:34 volumio volumio[905]: info: Output device has changed, restarting MPD Aug 22 20:39:34 volumio sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 22 20:39:34 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio volumio[905]: info: Output device has changed, restarting Shairport Sync Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:34 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 22 20:39:34 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 22 20:39:34 volumio volumio[905]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 22 20:39:34 volumio systemd[1]: mpd.service: Succeeded. Aug 22 20:39:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 22 20:39:34 volumio volumio[905]: info: Preparing to generate the ALSA configuration file Aug 22 20:39:34 volumio systemd[1]: Starting Music Player Daemon... Aug 22 20:39:34 volumio volumio[905]: info: MRS: Found cast device: Google-Nest-Hub-711c54ccde1ffe2a8cb2fda47341a5dc Aug 22 20:39:34 volumio volumio[905]: info: Adding audio output: Aug 22 20:39:34 volumio volumio[905]: info: MPD Permissions set Aug 22 20:39:34 volumio volumio[905]: info: Shairport-Sync Started Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: Asound.conf file unchanged, so no further update is needed Aug 22 20:39:34 volumio volumio[905]: info: Output device has changed, restarting MPD Aug 22 20:39:34 volumio sudo[2265]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 22 20:39:34 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 22 20:39:34 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: Output device has changed, restarting Shairport Sync Aug 22 20:39:34 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:34 volumio sudo[2274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 22 20:39:34 volumio sudo[2274]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio volumio[905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 22 20:39:34 volumio volumio[905]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: Successfully Updated MyVolumio device Aug 22 20:39:34 volumio volumio[905]: info: Starting Shairport Sync Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 22 20:39:34 volumio systemd[1]: mpd.service: Succeeded. Aug 22 20:39:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 22 20:39:34 volumio sudo[2284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 22 20:39:34 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio systemd[1]: Starting Music Player Daemon... Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 22 20:39:34 volumio volumio[905]: info: Starting Shairport Sync Aug 22 20:39:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 22 20:39:34 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 22 20:39:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio volumio[905]: info: Shairport-Sync Started Aug 22 20:39:34 volumio sudo[2293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 22 20:39:34 volumio sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 22 20:39:34 volumio sudo[2289]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 22 20:39:34 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:34 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 22 20:39:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 22 20:39:34 volumio sudo[2293]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:34 volumio volumio[905]: info: Shairport-Sync Started Aug 22 20:39:35 volumio volumio[905]: info: Successfully Updated MyVolumio device Aug 22 20:39:37 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 20:39:37 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 20:39:37 volumio volumio[905]: info: TidalConnect service stoped! Aug 22 20:39:37 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 22 20:39:37 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:37 volumio volumio[905]: info: MPD Permissions set Aug 22 20:39:37 volumio volumio[905]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 22 20:39:37 volumio volumio[905]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 22 20:39:37 volumio sudo[2323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 22 20:39:37 volumio sudo[2323]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:37 volumio mpd[2302]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 22 20:39:37 volumio mpd[2302]: output: No 'audio_output' defined in config file Aug 22 20:39:37 volumio mpd[2302]: output: Successfully detected a sndio audio device Aug 22 20:39:37 volumio mpd[2302]: zeroconf: No global port, disabling zeroconf Aug 22 20:39:37 volumio systemd[1]: Started Volumio Bluetooth Module. Aug 22 20:39:37 volumio systemd[1]: Started Music Player Daemon. Aug 22 20:39:37 volumio sudo[2274]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio sudo[2219]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Aug 22 20:39:37 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:37 volumio volumiobt[2325]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Aug 22 20:39:37 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio systemd[1]: Started Volumio Tidal Connect Service. Aug 22 20:39:37 volumio sudo[2323]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:37 volumio volumio[905]: info: Volumio BT Module successfully started Aug 22 20:39:37 volumio volumio[905]: error: MPD error: The expression evaluated to a falsy value: Aug 22 20:39:37 volumio volumio[905]: assert.ok(self.idling) Aug 22 20:39:37 volumio volumio[905]: error: The expression evaluated to a falsy value: Aug 22 20:39:37 volumio volumio[905]: assert.ok(self.idling) Aug 22 20:39:37 volumio volumio[905]: error: MPD error: The expression evaluated to a falsy value: Aug 22 20:39:37 volumio volumio[905]: assert.ok(self.idling) Aug 22 20:39:37 volumio volumio[905]: error: The expression evaluated to a falsy value: Aug 22 20:39:37 volumio volumio[905]: assert.ok(self.idling) Aug 22 20:39:37 volumio volumio[905]: error: updateQueue error: null Aug 22 20:39:38 volumio sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 22 20:39:38 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:38 volumio systemd[1]: Started MyVolumio SSH Tunnel. Aug 22 20:39:38 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:38 volumio volumio[905]: info: Remote SSH Started Aug 22 20:39:38 volumio autossh[2338]: port set to 0, monitoring disabled Aug 22 20:39:38 volumio autossh[2338]: starting ssh (count 1) Aug 22 20:39:38 volumio autossh[2338]: ssh child pid is 2341 Aug 22 20:39:38 volumio sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 22 20:39:38 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:39:38 volumio volumiobt[2325]: pulseaudio: no process found Aug 22 20:39:38 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Aug 22 20:39:38 volumio dbus-daemon[661]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.34' (uid=1000 pid=2347 comm="/usr/bin/pulseaudio --high-priority=true ") Aug 22 20:39:38 volumio volumio[905]: info: Executing endpoint tc_getconfig Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 22 20:39:38 volumio vtcs[2330]: STARTING TidalConnect services, version: 1.4.0.34 Aug 22 20:39:38 volumio volumio[905]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 22 20:39:38 volumio dbus-daemon[661]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 22 20:39:38 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 22 20:39:38 volumio volumio[905]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 22 20:39:38 volumio rtkit-daemon[2348]: Successfully called chroot. Aug 22 20:39:38 volumio rtkit-daemon[2348]: Successfully dropped privileges. Aug 22 20:39:38 volumio rtkit-daemon[2348]: Successfully limited resources. Aug 22 20:39:38 volumio rtkit-daemon[2348]: Running. Aug 22 20:39:38 volumio dbus-daemon[661]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.35' (uid=0 pid=2348 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 22 20:39:38 volumio rtkit-daemon[2348]: Canary thread running. Aug 22 20:39:38 volumio rtkit-daemon[2348]: Watchdog thread running. Aug 22 20:39:38 volumio vtcs[2330]: STARTED TidalConnect services. Aug 22 20:39:38 volumio systemd[1]: Starting Authorization Manager... Aug 22 20:39:38 volumio volumio[905]: info: Executing endpoint tc_connect Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 22 20:39:38 volumio volumio[905]: info: Connecting to TidalConnect Aug 22 20:39:38 volumio volumio[905]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::servicePushState Aug 22 20:39:38 volumio volumiossh-tunnel[2337]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: 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} Aug 22 20:39:38 volumio volumio[905]: verbose: CURRENT POSITION 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus stop Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio volumio[905]: info: No code Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::servicePushState Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: 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} Aug 22 20:39:38 volumio volumio[905]: verbose: CURRENT POSITION 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus stop Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio volumio[905]: info: No code Aug 22 20:39:38 volumio volumio[905]: info: CoreStateMachine::pushState Aug 22 20:39:38 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:38 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output update for this device Aug 22 20:39:38 volumio volumio[905]: info: MRS: Pushing multiroomSync output Aug 22 20:39:38 volumio polkitd[2358]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 22 20:39:38 volumio dbus-daemon[661]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 22 20:39:38 volumio systemd[1]: Started Authorization Manager. Aug 22 20:39:38 volumio volumio[905]: info: MRS: Getting audio outputs on start Aug 22 20:39:38 volumio volumio[905]: info: MRS: Requesting all other devices output Aug 22 20:39:39 volumio pulseaudio[2347]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Aug 22 20:39:39 volumio pulseaudio[2347]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Aug 22 20:39:39 volumio pulseaudio[2347]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Aug 22 20:39:39 volumio pulseaudio[2347]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Aug 22 20:39:39 volumio pulseaudio[2347]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 22 20:39:39 volumio pulseaudio[2347]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 22 20:39:39 volumio bluetoothd[744]: Endpoint registered: sender=:1.56 path=/MediaEndpoint/A2DPSource Aug 22 20:39:39 volumio bluetoothd[744]: Endpoint registered: sender=:1.56 path=/MediaEndpoint/A2DPSink Aug 22 20:39:39 volumio volumiobt[2325]: Applying permissions Aug 22 20:39:39 volumio volumiobt[2325]: Setting BT discoverable and pairable Aug 22 20:39:39 volumio pulseaudio[2347]: 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 Aug 22 20:39:39 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Aug 22 20:39:39 volumio kernel: Bluetooth: RFCOMM socket layer initialized Aug 22 20:39:39 volumio kernel: Bluetooth: RFCOMM ver 1.11 Aug 22 20:39:39 volumio volumiobt[2325]: Agent registered Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# -e power on Aug 22 20:39:39 volumio volumiobt[2325]: Invalid command in menu main: -e Aug 22 20:39:39 volumio volumiobt[2325]: Aug 22 20:39:39 volumio volumiobt[2325]: Use "help" for a list of available commands in a menu. Aug 22 20:39:39 volumio volumiobt[2325]: Use "menu " if you want to enter any submenu. Aug 22 20:39:39 volumio volumiobt[2325]: Use "back" if you want to return to menu main. Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# agent on Aug 22 20:39:39 volumio volumiobt[2325]: Agent is already registered Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# discoverable on Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# pairable on Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# agent NoInputNoOutput Aug 22 20:39:39 volumio volumiobt[2325]: Agent is already registered Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# default-agent Aug 22 20:39:39 volumio volumiobt[2325]: [bluetooth]# quit Aug 22 20:39:39 volumio volumiobt[2325]: [59B blob data] Aug 22 20:39:40 volumio volumio[905]: info: TidalConnect service started! Aug 22 20:39:43 volumio volumio[905]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Aug 22 20:39:46 volumio volumio[905]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/armhf Aug 22 20:39:46 volumio volumio[905]: info: Folder /tmp/plugins removed Aug 22 20:39:47 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:39:47 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:39:47 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:39:47 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:39:47 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:39:47 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:39:47 volumio volumio[905]: info: Check plugin dependencies Aug 22 20:39:47 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 22 20:39:47 volumio volumio[905]: info: Checking if plugin already exists Aug 22 20:39:47 volumio volumio[905]: info: Rename folder Aug 22 20:39:47 volumio volumio[905]: info: Folder /tmp/downloaded_plugin.zip removed Aug 22 20:39:47 volumio volumio[905]: info: Move to category Aug 22 20:40:02 volumio volumio[905]: info: Checking if install.sh is present Aug 22 20:40:02 volumio volumio[905]: info: Executing install.sh Aug 22 20:40:02 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 22 20:40:02 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 22 20:40:02 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Aug 22 20:40:02 volumio sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Aug 22 20:40:02 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 22 20:40:04 volumio volumio[905]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 22 20:40:04 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:04 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.1.189 from 192.168.1.159 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:40:06 volumio volumio[905]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: Listing playlists Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:40:06 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:40:06 volumio volumio[905]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: Listing playlists Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:40:06 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 22 20:40:06 volumio volumio[905]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: Listing playlists Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Aug 22 20:40:06 volumio volumio[905]: info: CoreStateMachine::getQueue Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getQueue Aug 22 20:40:06 volumio volumio[905]: info: Received Get System Info Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:06 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:06 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:40:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 22 20:40:12 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 22 20:40:12 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 20:40:15 volumio volumio[905]: info: Enabling plugin now_playing Aug 22 20:40:15 volumio volumio[905]: info: Loading plugin "now_playing"... Aug 22 20:40:15 volumio volumio[905]: error: !!!! WARNING !!!! Aug 22 20:40:15 volumio volumio[905]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 22 20:40:15 volumio volumio[905]: Require stack: Aug 22 20:40:15 volumio volumio[905]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/app/pluginmanager.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/app/index.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/index.js Aug 22 20:40:15 volumio volumio[905]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 22 20:40:15 volumio volumio[905]: Require stack: Aug 22 20:40:15 volumio volumio[905]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/app/pluginmanager.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/app/index.js Aug 22 20:40:15 volumio volumio[905]: - /volumio/index.js Aug 22 20:40:15 volumio volumio[905]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Aug 22 20:40:15 volumio volumio[905]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Aug 22 20:40:15 volumio volumio[905]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 22 20:40:15 volumio volumio[905]: at require (internal/modules/cjs/helpers.js:88:18) Aug 22 20:40:15 volumio volumio[905]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 22 20:40:15 volumio volumio[905]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Aug 22 20:40:15 volumio volumio[905]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Aug 22 20:40:15 volumio volumio[905]: at Module.load (internal/modules/cjs/loader.js:928:32) Aug 22 20:40:15 volumio volumio[905]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Aug 22 20:40:15 volumio volumio[905]: at Module.require (internal/modules/cjs/loader.js:952:19) Aug 22 20:40:15 volumio volumio[905]: at require (internal/modules/cjs/helpers.js:88:18) Aug 22 20:40:15 volumio volumio[905]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 22 20:40:15 volumio volumio[905]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 22 20:40:15 volumio volumio[905]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 22 20:40:15 volumio volumio[905]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 22 20:40:15 volumio volumio[905]: error: !!!! WARNING !!!! Aug 22 20:40:15 volumio volumio[905]: info: Done. Aug 22 20:40:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 22 20:40:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 22 20:40:17 volumio volumio[905]: info: Discovery: Getting this device information Aug 22 20:40:17 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Aug 22 20:40:17 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Aug 22 20:40:17 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 22 20:40:18 volumio volumio[905]: info: Starting Uninstall of plugin user_interface - now_playing Aug 22 20:40:18 volumio volumio[905]: info: Uninstalling plugin now_playing Aug 22 20:40:28 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 22 20:40:30 volumio volumio[905]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/0.7.3/volumio/buster/armhf Aug 22 20:40:30 volumio volumio[905]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 20:40:30 volumio volumio[905]: TypeError: Cannot read property 'broadcastMessage' of null Aug 22 20:40:30 volumio volumio[905]: at /volumio/app/index.js:1515:32 Aug 22 20:40:30 volumio volumio[905]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 22 20:40:30 volumio volumio[905]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 22 20:40:30 volumio volumio[905]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1513:13) Aug 22 20:40:30 volumio volumio[905]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Aug 22 20:40:30 volumio volumio[905]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Aug 22 20:40:30 volumio volumio[905]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1434:22) Aug 22 20:40:30 volumio volumio[905]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1195:47) Aug 22 20:40:30 volumio volumio[905]: at Socket.emit (events.js:315:20) Aug 22 20:40:30 volumio volumio[905]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 22 20:40:30 volumio volumio[905]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 22 20:40:30 volumio volumio[905]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 22 20:40:31 volumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-22 20:39 Aug 22 20:40:31 volumio sudo[2458]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"