-- Logs begin at Fri 2024-11-29 12:28:22 CET, end at Fri 2024-11-29 12:30:26 CET. -- Nov 29 12:29:01 rpi4 volumio[1265]: info: Initializing connection to go-librespot Websocket Nov 29 12:29:01 rpi4 go-librespot[1497]: time="2024-11-29T12:29:01+01:00" level=debug msg="new websocket client" Nov 29 12:29:01 rpi4 volumio[1265]: info: Connection to go-librespot Websocket established Nov 29 12:29:03 rpi4 volumio-remote-updater[829]: [2024-11-29 12:29:03] [connect] Successful connection Nov 29 12:29:04 rpi4 volumio[1620]: ..................................................................................................++++ Nov 29 12:29:04 rpi4 volumio[1620]: e is 65537 (0x010001) Nov 29 12:29:04 rpi4 volumio[1620]: writing RSA key Nov 29 12:29:05 rpi4 volumio[1265]: info: Getting Spotify volume Nov 29 12:29:05 rpi4 volumio-remote-updater[829]: [2024-11-29 12:29:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732879743 101 Nov 29 12:29:05 rpi4 volumio[1265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Nov 29 12:29:05 rpi4 volumio[1265]: 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 29 12:29:05 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:05 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:05 rpi4 volumio[1265]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 29 12:29:05 rpi4 volumio[1265]: SPOTIFY: SPOTIFY VOLUME undefined Nov 29 12:29:05 rpi4 volumio[1265]: SPOTIFY: VOLUMIO VOLUME 100 Nov 29 12:29:05 rpi4 volumio[1265]: info: Aligning Spotify Volume to Volumio Volume Nov 29 12:29:05 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:05 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:05 rpi4 volumio[1265]: info: Setting Spotify Volume from Volumio: 100 Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin bluetooth to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin multiroom to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin metavolumio to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin cd_controller to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 29 12:29:06 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:06 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:06 rpi4 volumio[1265]: info: Starting MyVolumio Remote Streaming Endpoints Nov 29 12:29:06 rpi4 volumio[1265]: info: MyVolumio login type: Token Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 29 12:29:06 rpi4 volumio[1265]: info: Starting Streaming Service Transparent Proxy Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 29 12:29:06 rpi4 volumio[1265]: info: Streaming services startup Nov 29 12:29:06 rpi4 volumio[1265]: info: Starting Streaming Daemon Nov 29 12:29:06 rpi4 sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 29 12:29:06 rpi4 sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:06 rpi4 sudo[1987]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:06 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 29 12:29:06 rpi4 volumio[1265]: error: Cannot start Volumio Streaming Daemon Nov 29 12:29:06 rpi4 volumio[1265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 29 12:29:06 rpi4 volumio[1265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 29 12:29:07 rpi4 volumio[1265]: error: MyVolumio Custom Token format not valid, refreshing it Nov 29 12:29:07 rpi4 volumio[1265]: STREAMING PROXY: Starting server on port 3245 Nov 29 12:29:07 rpi4 volumio[1265]: Node JS runtime: 14 Nov 29 12:29:07 rpi4 volumio[1265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 29 12:29:07 rpi4 volumio[1265]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Nov 29 12:29:07 rpi4 volumio[1265]: info: Sending Spotify command with payload to local API: /player/volume Nov 29 12:29:07 rpi4 volumio[1265]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Nov 29 12:29:07 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 12:29:07 rpi4 volumio[1265]: info: MyVolumio login type: Token Nov 29 12:29:07 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 12:29:07 rpi4 volumio[1265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 29 12:29:07 rpi4 volumio[1265]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 29 12:29:07 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:08 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 12:29:08 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:08 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:08 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:08 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:08 rpi4 volumio[1265]: info: Listing playlists Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 29 12:29:08 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 12:29:08 rpi4 volumio[1265]: info: MyVolumio token set successfully Nov 29 12:29:08 rpi4 volumio[1265]: info: MYVOLUMIO: Adding device Nov 29 12:29:08 rpi4 volumio[1265]: info: MYVOLUMIO: Evaluating Server Nov 29 12:29:08 rpi4 volumio[1265]: info: MyVolumio Plan changed: premium Nov 29 12:29:08 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 29 12:29:08 rpi4 volumio[1265]: info: Removing browser output: myVolumio user plan is not superstar Nov 29 12:29:08 rpi4 volumio[1265]: info: Removing audio output: Nov 29 12:29:08 rpi4 volumio[1265]: info: MYVOLUMIO: Adding device Nov 29 12:29:08 rpi4 volumio[1265]: info: MYVOLUMIO: Evaluating Server Nov 29 12:29:08 rpi4 volumio[1265]: info: Remote config written successfully Nov 29 12:29:08 rpi4 volumio[1265]: info: Starting Tunnel 1 Nov 29 12:29:08 rpi4 volumio[1265]: info: Starting Tunnel Connection Checker Nov 29 12:29:08 rpi4 volumio[1265]: info: Completed starting MyVolumio Plugin Nov 29 12:29:09 rpi4 volumio[1265]: info: MYVolumio Device enabled Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 29 12:29:09 rpi4 volumio[1265]: info: MyVolumio status changed Nov 29 12:29:09 rpi4 volumio[1265]: info: Streaming services startup Nov 29 12:29:09 rpi4 volumio[1265]: info: Starting Streaming Daemon Nov 29 12:29:09 rpi4 volumio[1265]: info: Setting Geolocation for MyVolumio to eu9 Nov 29 12:29:09 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:09 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:09 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:09 rpi4 sudo[2027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 29 12:29:09 rpi4 sudo[2027]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 sudo[2027]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 volumio[1265]: error: Cannot start Volumio Streaming Daemon Nov 29 12:29:09 rpi4 volumio[1265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 29 12:29:09 rpi4 volumio[1265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 29 12:29:09 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [1732879749352] Starting BluetoothController Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 29 12:29:09 rpi4 sudo[2030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 29 12:29:09 rpi4 sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 sudo[2030]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 volumio[1265]: info: MRS: MultiRoom plugin initialized Nov 29 12:29:09 rpi4 volumio[1265]: info: MRS: STOPPING SNAPCLIENT Nov 29 12:29:09 rpi4 volumio[1265]: info: MRS: Snap server stop Nov 29 12:29:09 rpi4 sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 29 12:29:09 rpi4 sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 volumio[1265]: info: MRS: STOPPING volumioStreaming Nov 29 12:29:09 rpi4 sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 29 12:29:09 rpi4 sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 sudo[2046]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 sudo[2049]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 29 12:29:09 rpi4 sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 29 12:29:09 rpi4 sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 29 12:29:09 rpi4 sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:09 rpi4 sudo[2054]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 sudo[2053]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 29 12:29:09 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 29 12:29:09 rpi4 volumio[1265]: info: Preparing to generate the ALSA configuration file Nov 29 12:29:09 rpi4 volumio[1265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 29 12:29:09 rpi4 volumio[1265]: info: Reading ALSA contributions from plugins. Nov 29 12:29:10 rpi4 volumio[1265]: info: Setting Geolocation for MyVolumio to eu8 Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:29:10 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:10 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:10 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: Removed streaming files Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: volumioStreaming STOPPED Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: SNAPSERVER STOPPED Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: SNAPCLIENT STOPPED Nov 29 12:29:10 rpi4 volumio[1265]: info: Asound.conf file unchanged, so no further update is needed Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting MPD Nov 29 12:29:10 rpi4 sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:29:10 rpi4 sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 sudo[2058]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting Shairport Sync Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:29:10 rpi4 sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 systemd[1]: Stopping Music Player Daemon... Nov 29 12:29:10 rpi4 volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 systemd[1]: mpd.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Music Player Daemon. Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding METAVOLUMIO REST API Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 29 12:29:10 rpi4 volumio[1265]: info: Preparing CD Folders Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding CD REST API Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 29 12:29:10 rpi4 volumio[1265]: info: Starting UDEV Watcher for CD Nov 29 12:29:10 rpi4 volumio[1265]: info: Detecting CD presence with UDEV Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding inputs REST Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 29 12:29:10 rpi4 volumio[1265]: info: Scanning Audio Inputs Nov 29 12:29:10 rpi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:29:10 rpi4 volumio[1265]: info: Checking against Known Cards name Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 29 12:29:10 rpi4 volumio[1265]: info: [1732879750134] CoreMusicLibrary::Adding element HiFiBerry ADC Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source Bandcamp Discover Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source Mixcloud Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source 80s80s Radio Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source LastFM Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source Spotify Nov 29 12:29:10 rpi4 volumio[1265]: Cannot find translation for source HiFiBerry ADC Nov 29 12:29:10 rpi4 volumio[1265]: info: Checking against Known Cards name Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding Server instance for streaming Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 29 12:29:10 rpi4 volumio[1265]: error: Hi Res Audio Failed Login: Missing Login Data Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 29 12:29:10 rpi4 volumio[1265]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Nov 29 12:29:10 rpi4 volumio[1265]: info: Stopping AccessToken refresher cron Nov 29 12:29:10 rpi4 sudo[2068]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:29:10 rpi4 sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 sudo[2068]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: AccessToken refresher cron started Nov 29 12:29:10 rpi4 volumio[1265]: info: Stopping AccessToken refresher cron for QOBUZ Nov 29 12:29:10 rpi4 volumio[1265]: info: AccessToken refresher cron started for QOBUZ Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding TIDAL REST API Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding QOBUZ REST API Endpoints Nov 29 12:29:10 rpi4 volumio[1265]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 29 12:29:10 rpi4 volumio[1265]: info: BOOT COMPLETED Nov 29 12:29:10 rpi4 volumio[1265]: [Metrics] CommandRouter: 22s 330.16ms Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::volumiosetStartupVolume Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: VolumeController:: Setting startup Volume 100 Nov 29 12:29:10 rpi4 volumio[1265]: info: VolumeController::SetAlsaVolume100 Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::Close All Modals sent Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::Close All Modals sent Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreStateMachine::pushState Nov 29 12:29:10 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::volumioPushState Nov 29 12:29:10 rpi4 volumio[1265]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 29 12:29:10 rpi4 volumio[1265]: SPOTIFY: SPOTIFY VOLUME 100 Nov 29 12:29:10 rpi4 volumio[1265]: SPOTIFY: VOLUMIO VOLUME 100 Nov 29 12:29:10 rpi4 volumio[1265]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 29 12:29:10 rpi4 volumio[1265]: info: MPD Permissions set Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: Starting Shairport Sync Nov 29 12:29:10 rpi4 volumio[1265]: info: Preparing to generate the ALSA configuration file Nov 29 12:29:10 rpi4 sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:29:10 rpi4 sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 volumio[1265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 29 12:29:10 rpi4 volumio[1265]: info: Reading ALSA contributions from plugins. Nov 29 12:29:10 rpi4 volumio[1265]: info: Asound.conf file unchanged, so no further update is needed Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting MPD Nov 29 12:29:10 rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:29:10 rpi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 sudo[2084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:29:10 rpi4 sudo[2084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting Shairport Sync Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 sudo[2084]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:29:10 rpi4 sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 sudo[2081]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 12:29:10 rpi4 systemd[1]: mpd.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Music Player Daemon. Nov 29 12:29:10 rpi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:29:10 rpi4 volumio[1265]: info: Updating MyVolumio device info Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: MPD Permissions set Nov 29 12:29:10 rpi4 volumio[1265]: info: Shairport-Sync Started Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 sudo[2096]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:29:10 rpi4 sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 sudo[2096]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 29 12:29:10 rpi4 volumio[1265]: info: Starting Shairport Sync Nov 29 12:29:10 rpi4 volumio[1265]: info: Preparing to generate the ALSA configuration file Nov 29 12:29:10 rpi4 sudo[2105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:29:10 rpi4 sudo[2105]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 volumio[1265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 29 12:29:10 rpi4 volumio[1265]: info: Reading ALSA contributions from plugins. Nov 29 12:29:10 rpi4 volumio[1265]: info: Asound.conf file unchanged, so no further update is needed Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting MPD Nov 29 12:29:10 rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:29:10 rpi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 29 12:29:10 rpi4 sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 volumio[1265]: info: Output device has changed, restarting Shairport Sync Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 sudo[2108]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 sudo[2105]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 29 12:29:10 rpi4 sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 29 12:29:10 rpi4 systemd[1]: mpd.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Music Player Daemon. Nov 29 12:29:10 rpi4 volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 29 12:29:10 rpi4 systemd[1]: Starting Music Player Daemon... Nov 29 12:29:10 rpi4 volumio[1265]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 sudo[2119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 29 12:29:10 rpi4 sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 sudo[2119]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: MPD Permissions set Nov 29 12:29:10 rpi4 volumio[1265]: info: Shairport-Sync Started Nov 29 12:29:10 rpi4 volumio[1265]: info: Successfully Added MyVolumio device Nov 29 12:29:10 rpi4 volumio[1265]: info: Successfully Added MyVolumio device Nov 29 12:29:10 rpi4 volumio[1265]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:10 rpi4 volumio[1265]: info: Starting Shairport Sync Nov 29 12:29:10 rpi4 sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 29 12:29:10 rpi4 sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:10 rpi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 29 12:29:10 rpi4 systemd[1]: shairport-sync.service: Succeeded. Nov 29 12:29:10 rpi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 29 12:29:10 rpi4 sudo[2129]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: info: Shairport-Sync Started Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:29:10 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:10 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:10 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:10 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:10 rpi4 volumio[1265]: info: Successfully Updated MyVolumio device Nov 29 12:29:10 rpi4 mpd[2123]: Nov 29 12:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 29 12:29:10 rpi4 systemd[1]: Started Music Player Daemon. Nov 29 12:29:10 rpi4 sudo[2061]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 sudo[2112]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 sudo[2088]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:10 rpi4 volumio[1265]: error: MPD error: The expression evaluated to a falsy value: Nov 29 12:29:10 rpi4 volumio[1265]: assert.ok(self.idling) Nov 29 12:29:10 rpi4 volumio[1265]: error: The expression evaluated to a falsy value: Nov 29 12:29:10 rpi4 volumio[1265]: assert.ok(self.idling) Nov 29 12:29:10 rpi4 volumio[1265]: error: MPD error: The expression evaluated to a falsy value: Nov 29 12:29:10 rpi4 volumio[1265]: assert.ok(self.idling) Nov 29 12:29:10 rpi4 volumio[1265]: error: The expression evaluated to a falsy value: Nov 29 12:29:10 rpi4 volumio[1265]: assert.ok(self.idling) Nov 29 12:29:10 rpi4 volumio[1265]: error: updateQueue error: null Nov 29 12:29:11 rpi4 volumio[1265]: info: Updating MyVolumio device info Nov 29 12:29:11 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:11 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:11 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 29 12:29:11 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 29 12:29:11 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 29 12:29:11 rpi4 volumio[1265]: info: Successfully Updated MyVolumio device Nov 29 12:29:14 rpi4 sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 29 12:29:14 rpi4 sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:14 rpi4 systemd[1]: Started MyVolumio SSH Tunnel. Nov 29 12:29:14 rpi4 sudo[2142]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:14 rpi4 volumio[1265]: info: Remote SSH Started Nov 29 12:29:14 rpi4 autossh[2145]: port set to 0, monitoring disabled Nov 29 12:29:14 rpi4 autossh[2145]: starting ssh (count 1) Nov 29 12:29:14 rpi4 autossh[2145]: ssh child pid is 2148 Nov 29 12:29:14 rpi4 volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 29 12:29:14 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:14 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:14 rpi4 volumiossh-tunnel[2144]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Nov 29 12:29:17 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:21 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 12:29:21 rpi4 volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 29 12:29:21 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:21 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.129:3000 from 192.168.1.79 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:22 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:22 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:22 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 15 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.81 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 17 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 18 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 19 Nov 29 12:29:22 rpi4 volumio[1265]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 20 Nov 29 12:29:22 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:22 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetVisibleSources Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetQueue Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreStateMachine::getQueue Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getQueue Nov 29 12:29:22 rpi4 volumio[1265]: info: Listing playlists Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 29 12:29:22 rpi4 volumio[1265]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 29 12:29:22 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:22 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:22 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:22 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 29 12:29:24 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:29:24 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 29 12:29:24 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 29 12:29:24 rpi4 volumio-remote-updater[829]: Test mode enabled Nov 29 12:29:24 rpi4 volumio-remote-updater[829]: No alpha test mode Nov 29 12:29:24 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 29 12:29:24 rpi4 volumio[1265]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

","title":"Update v3.782","updateavailable":true} Nov 29 12:29:24 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 29 12:29:25 rpi4 volumio[1265]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 29 12:29:25 rpi4 volumio[1265]: info: Received Get System Version Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:29:25 rpi4 volumio[1265]: info: Received Get System Info Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 29 12:29:25 rpi4 volumio[1265]: info: Discovery: Getting this device information Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetState Nov 29 12:29:25 rpi4 volumio[1265]: info: CorePlayQueue::getTrack 0 Nov 29 12:29:25 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Nov 29 12:29:26 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:29:31 rpi4 ntpd[978]: Soliciting pool server 193.123.37.231 Nov 29 12:29:32 rpi4 ntpd[978]: Soliciting pool server 84.82.15.107 Nov 29 12:29:32 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 29 12:29:33 rpi4 ntpd[978]: Soliciting pool server 94.198.159.16 Nov 29 12:29:33 rpi4 ntpd[978]: Soliciting pool server 193.123.56.220 Nov 29 12:29:34 rpi4 ntpd[978]: Soliciting pool server 213.109.127.82 Nov 29 12:29:34 rpi4 ntpd[978]: Soliciting pool server 87.238.174.133 Nov 29 12:29:35 rpi4 ntpd[978]: Soliciting pool server 178.128.142.106 Nov 29 12:29:36 rpi4 volumio[1265]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/buster/armhf Nov 29 12:29:36 rpi4 ntpd[978]: Soliciting pool server 45.92.156.47 Nov 29 12:29:36 rpi4 ntpd[978]: Soliciting pool server 193.148.248.179 Nov 29 12:29:37 rpi4 ntpd[978]: Soliciting pool server 5.39.184.5 Nov 29 12:29:37 rpi4 ntpd[978]: Soliciting pool server 185.51.192.63 Nov 29 12:29:37 rpi4 volumio[1265]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/buster/armhf Nov 29 12:29:37 rpi4 volumio[1265]: info: Folder /tmp/plugins removed Nov 29 12:29:38 rpi4 volumio[1265]: info: Check plugin dependencies Nov 29 12:29:38 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 29 12:29:38 rpi4 volumio[1265]: info: Checking if plugin already exists Nov 29 12:29:38 rpi4 volumio[1265]: info: Rename folder Nov 29 12:29:38 rpi4 volumio[1265]: info: Folder /tmp/downloaded_plugin.zip removed Nov 29 12:29:38 rpi4 volumio[1265]: info: Move to category Nov 29 12:29:38 rpi4 ntpd[978]: Soliciting pool server 162.159.200.1 Nov 29 12:29:38 rpi4 ntpd[978]: Soliciting pool server 94.198.159.15 Nov 29 12:29:39 rpi4 ntpd[978]: Soliciting pool server 45.138.55.61 Nov 29 12:29:39 rpi4 ntpd[978]: Soliciting pool server 45.83.205.227 Nov 29 12:29:40 rpi4 ntpd[978]: Soliciting pool server 2001:418:3ff::53 Nov 29 12:29:41 rpi4 volumio[1265]: info: Checking if install.sh is present Nov 29 12:29:41 rpi4 volumio[1265]: info: Executing install.sh Nov 29 12:29:41 rpi4 sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Nov 29 12:29:41 rpi4 sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 29 12:29:41 rpi4 volumio[1265]: info: Installing geo-tz node dependency Nov 29 12:29:50 rpi4 volumio[1265]: info: + geo-tz@8.1.2 Nov 29 12:29:50 rpi4 volumio[1265]: info: added 29 packages from 33 contributors and audited 353 packages in 7.547s Nov 29 12:29:50 rpi4 volumio[1265]: info: Nov 29 12:29:50 rpi4 volumio[1265]: info: 21 packages are looking for funding Nov 29 12:29:50 rpi4 volumio[1265]: info: run `npm fund` for details Nov 29 12:29:50 rpi4 volumio[1265]: info: Nov 29 12:29:50 rpi4 volumio[1265]: info: found 11 vulnerabilities (5 low, 3 moderate, 3 high) Nov 29 12:29:50 rpi4 volumio[1265]: info: run `npm audit fix` to fix them, or `npm audit` for details Nov 29 12:29:50 rpi4 volumio[1265]: info: Creating user directories (if not exist) Nov 29 12:29:50 rpi4 sudo[2207]: pam_unix(sudo:session): session closed for user root Nov 29 12:29:50 rpi4 volumio[1265]: info: Now Playing plugin installed Nov 29 12:29:50 rpi4 volumio[1265]: Plugin install end detected on script Nov 29 12:29:50 rpi4 volumio[1265]: info: Install script completed Nov 29 12:29:50 rpi4 volumio[1265]: info: Adding reference to registry Nov 29 12:29:50 rpi4 volumio[1265]: info: Done installing plugin. Nov 29 12:29:50 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 12:29:50 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 29 12:29:50 rpi4 volumio[1265]: info: Folder /tmp/plugins removed Nov 29 12:29:50 rpi4 volumio[1265]: info: Folder /tmp/downloaded_plugin.zip removed Nov 29 12:29:50 rpi4 volumio[1265]: info: Folder /data/temp removed Nov 29 12:29:54 rpi4 volumio[1265]: info: Enabling plugin now_playing Nov 29 12:29:54 rpi4 volumio[1265]: info: Loading plugin "now_playing"... Nov 29 12:29:54 rpi4 volumio[1265]: error: !!!! WARNING !!!! Nov 29 12:29:54 rpi4 volumio[1265]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error [ERR_REQUIRE_ESM]: Must use import to load ES Module: /data/plugins/user_interface/now_playing/node_modules/point-in-polygon-hao/dist/pointInPolygon.mjs Nov 29 12:29:54 rpi4 volumio[1265]: error: Stack trace: Error [ERR_REQUIRE_ESM]: Must use import to load ES Module: /data/plugins/user_interface/now_playing/node_modules/point-in-polygon-hao/dist/pointInPolygon.mjs Nov 29 12:29:54 rpi4 volumio[1265]: at Module.load (internal/modules/cjs/loader.js:926:11) Nov 29 12:29:54 rpi4 volumio[1265]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 29 12:29:54 rpi4 volumio[1265]: at Module.require (internal/modules/cjs/loader.js:952:19) Nov 29 12:29:54 rpi4 volumio[1265]: at require (internal/modules/cjs/helpers.js:88:18) Nov 29 12:29:54 rpi4 volumio[1265]: at Object. (/data/plugins/user_interface/now_playing/node_modules/@turf/boolean-point-in-polygon/dist/cjs/index.cjs:2:26) Nov 29 12:29:54 rpi4 volumio[1265]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 29 12:29:54 rpi4 volumio[1265]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 29 12:29:54 rpi4 volumio[1265]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 29 12:29:54 rpi4 volumio[1265]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 29 12:29:54 rpi4 volumio[1265]: at Module.require (internal/modules/cjs/loader.js:952:19) Nov 29 12:29:54 rpi4 volumio[1265]: at require (internal/modules/cjs/helpers.js:88:18) Nov 29 12:29:54 rpi4 volumio[1265]: at Object. (/data/plugins/user_interface/now_playing/node_modules/geo-tz/dist/find-1970.js:1:61) Nov 29 12:29:54 rpi4 volumio[1265]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 29 12:29:54 rpi4 volumio[1265]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 29 12:29:54 rpi4 volumio[1265]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 29 12:29:54 rpi4 volumio[1265]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 29 12:29:54 rpi4 volumio[1265]: error: !!!! WARNING !!!! Nov 29 12:29:54 rpi4 volumio[1265]: info: Done. Nov 29 12:30:23 rpi4 volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 29 12:30:26 rpi4 volumio[1265]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/0.7.4/volumio/buster/armhf Nov 29 12:30:26 rpi4 volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:30:26 rpi4 volumio[1265]: TypeError: Cannot read property 'broadcastMessage' of null Nov 29 12:30:26 rpi4 volumio[1265]: at /volumio/app/index.js:1515:32 Nov 29 12:30:26 rpi4 volumio[1265]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Nov 29 12:30:26 rpi4 volumio[1265]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Nov 29 12:30:26 rpi4 volumio[1265]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1513:13) Nov 29 12:30:26 rpi4 volumio[1265]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Nov 29 12:30:26 rpi4 volumio[1265]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Nov 29 12:30:26 rpi4 volumio[1265]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1434:22) Nov 29 12:30:26 rpi4 volumio[1265]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1195:47) Nov 29 12:30:26 rpi4 volumio[1265]: at Socket.emit (events.js:315:20) Nov 29 12:30:26 rpi4 volumio[1265]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 29 12:30:26 rpi4 volumio[1265]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 29 12:30:26 rpi4 volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 12:30:26 rpi4 sudo[2296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 12:29 Nov 29 12:30:26 rpi4 sudo[2296]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 12 Sep 2024 07:42:41 PM CEST" VOLUMIO_VERSION="3.754" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e445099d9dc378478f3bbde5df265b81"