-- Logs begin at Sun 2024-03-31 18:25:14 CEST, end at Sun 2024-03-31 18:32:36 CEST. -- Mar 31 18:31:20 sekt88 volumio[724]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 31 18:31:20 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 31 18:31:20 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:21 sekt88 volumio[724]: info: Starting MyVolumio Remote Streaming Endpoints Mar 31 18:31:21 sekt88 volumio[724]: info: MyVolumio login type: Token Mar 31 18:31:21 sekt88 volumio[724]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 31 18:31:21 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 31 18:31:24 sekt88 volumio[724]: info: Starting Streaming Service Transparent Proxy Mar 31 18:31:24 sekt88 volumio[724]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 31 18:31:24 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 31 18:31:24 sekt88 volumio[724]: info: Streaming services startup Mar 31 18:31:24 sekt88 volumio[724]: info: Starting Streaming Daemon Mar 31 18:31:24 sekt88 volumio[724]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 31 18:31:25 sekt88 sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 31 18:31:25 sekt88 sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:25 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2070, instance 1, run: true Mar 31 18:31:26 sekt88 sudo[2058]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:26 sekt88 volumio[724]: error: Cannot start Volumio Streaming Daemon Mar 31 18:31:26 sekt88 volumio[724]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 31 18:31:26 sekt88 volumio[724]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 31 18:31:26 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2077, instance 1, run: true Mar 31 18:31:26 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2084, instance 1, run: true Mar 31 18:31:27 sekt88 volumio[724]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 31 18:31:27 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2091, instance 1, run: true Mar 31 18:31:28 sekt88 volumio[724]: info: MyVolumio token set successfully Mar 31 18:31:28 sekt88 volumio[724]: info: MYVOLUMIO: Adding device Mar 31 18:31:28 sekt88 volumio[724]: info: MYVOLUMIO: Evaluating Server Mar 31 18:31:28 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2100, instance 1, run: true Mar 31 18:31:28 sekt88 volumio[724]: info: MyVolumio Plan changed: premium Mar 31 18:31:28 sekt88 volumio[724]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 31 18:31:28 sekt88 volumio[724]: info: Removing browser output: myVolumio user plan is not superstar Mar 31 18:31:28 sekt88 volumio[724]: info: Removing audio output: Mar 31 18:31:28 sekt88 volumio[724]: info: MYVOLUMIO: Adding device Mar 31 18:31:28 sekt88 volumio[724]: info: MYVOLUMIO: Evaluating Server Mar 31 18:31:28 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2122, instance 1, run: true Mar 31 18:31:28 sekt88 volumio[724]: info: Remote config written successfully Mar 31 18:31:28 sekt88 volumio[724]: info: Starting Tunnel 1 Mar 31 18:31:28 sekt88 volumio[724]: info: Starting Tunnel Connection Checker Mar 31 18:31:29 sekt88 volumio[724]: info: Completed starting MyVolumio Plugin Mar 31 18:31:29 sekt88 volumio[724]: info: MYVolumio Device enabled Mar 31 18:31:29 sekt88 volumio[724]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 31 18:31:29 sekt88 volumio[724]: info: MyVolumio status changed Mar 31 18:31:29 sekt88 volumio[724]: info: Streaming services startup Mar 31 18:31:29 sekt88 volumio[724]: info: Starting Streaming Daemon Mar 31 18:31:29 sekt88 volumio[724]: info: Setting Geolocation for MyVolumio to eu5 Mar 31 18:31:29 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:29 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:29 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:30 sekt88 sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 31 18:31:30 sekt88 sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:30 sekt88 sudo[2145]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:31 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2147, instance 1, run: true Mar 31 18:31:31 sekt88 volumio[724]: info: Updating MyVolumio device info Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: Setting Geolocation for MyVolumio to eu2 Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:31 sekt88 volumio[724]: error: Cannot start Volumio Streaming Daemon Mar 31 18:31:31 sekt88 volumio[724]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 31 18:31:31 sekt88 volumio[724]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 31 18:31:31 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:32 sekt88 volumio[724]: STREAMING PROXY: Starting server on port 3245 Mar 31 18:31:32 sekt88 volumio[724]: Node JS runtime: 14 Mar 31 18:31:32 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 31 18:31:35 sekt88 volumio[724]: info: [1711902695807] Starting BluetoothController Mar 31 18:31:36 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 31 18:31:41 sekt88 volumio[724]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 31 18:31:42 sekt88 sudo[2156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 31 18:31:42 sekt88 sudo[2156]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:42 sekt88 sudo[2156]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:42 sekt88 volumio[724]: info: MRS: MultiRoom plugin initialized Mar 31 18:31:42 sekt88 volumio[724]: info: MRS: STOPPING SNAPCLIENT Mar 31 18:31:42 sekt88 volumio[724]: info: MRS: Snap server stop Mar 31 18:31:42 sekt88 sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 31 18:31:42 sekt88 volumio[724]: info: MRS: STOPPING volumioStreaming Mar 31 18:31:42 sekt88 sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:42 sekt88 sudo[2174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 31 18:31:42 sekt88 sudo[2174]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:42 sekt88 sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 31 18:31:42 sekt88 sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:42 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 31 18:31:42 sekt88 sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 31 18:31:42 sekt88 sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:42 sekt88 sudo[2180]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 31 18:31:43 sekt88 sudo[2172]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:43 sekt88 sudo[2177]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:43 sekt88 sudo[2174]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 31 18:31:43 sekt88 volumio[724]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 31 18:31:43 sekt88 volumio[724]: info: Preparing to generate the ALSA configuration file Mar 31 18:31:44 sekt88 volumio[724]: info: Updating MyVolumio device info Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 18:31:44 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 31 18:31:44 sekt88 volumio[724]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 31 18:31:44 sekt88 volumio[724]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 31 18:31:44 sekt88 volumio[724]: info: Reading ALSA contributions from plugins. Mar 31 18:31:44 sekt88 volumio[724]: info: Successfully Added MyVolumio device Mar 31 18:31:44 sekt88 volumio[724]: info: MRS: Removed streaming files Mar 31 18:31:44 sekt88 volumio[724]: info: MRS: volumioStreaming STOPPED Mar 31 18:31:44 sekt88 volumio[724]: info: MRS: SNAPSERVER STOPPED Mar 31 18:31:44 sekt88 volumio[724]: info: MRS: SNAPCLIENT STOPPED Mar 31 18:31:44 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2224, instance 1, run: true Mar 31 18:31:44 sekt88 volumio[724]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 31 18:31:45 sekt88 volumio[724]: info: CoreCommandRouter::volumioGetState Mar 31 18:31:45 sekt88 volumio[724]: info: CorePlayQueue::getTrack 0 Mar 31 18:31:45 sekt88 volumio[724]: info: Successfully Updated MyVolumio device Mar 31 18:31:45 sekt88 volumio[724]: info: Asound.conf file written Mar 31 18:31:46 sekt88 sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 31 18:31:46 sekt88 sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 31 18:31:46 sekt88 sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:46 sekt88 sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:47 sekt88 sudo[2238]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:47 sekt88 systemd[1]: Started MyVolumio SSH Tunnel. Mar 31 18:31:47 sekt88 sudo[2236]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:47 sekt88 volumio[724]: info: Output device has changed, restarting MPD Mar 31 18:31:47 sekt88 volumio[724]: info: Output device has changed, restarting Shairport Sync Mar 31 18:31:47 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:31:47 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:47 sekt88 sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 31 18:31:47 sekt88 sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 31 18:31:47 sekt88 sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:47 sekt88 sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:47 sekt88 sudo[2246]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:48 sekt88 systemd[1]: Stopping Music Player Daemon... Mar 31 18:31:48 sekt88 volumio[724]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 31 18:31:48 sekt88 volumio[724]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 18:31:48 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:31:48 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 31 18:31:48 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 18:31:48 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 31 18:31:48 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 31 18:31:48 sekt88 volumio[724]: info: MRS: Pushing multiroomSync output for this device Mar 31 18:31:48 sekt88 volumio[724]: info: MRS: Pushing multiroomSync output Mar 31 18:31:48 sekt88 volumio[724]: info: Adding audio output: Mar 31 18:31:48 sekt88 volumio[724]: info: Adding audio output: Mar 31 18:31:48 sekt88 volumio[724]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 31 18:31:48 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:31:48 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 31 18:31:48 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:31:48 sekt88 volumio[724]: info: Adding METAVOLUMIO REST API Endpoints Mar 31 18:31:48 sekt88 volumio[724]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 31 18:31:48 sekt88 volumio[724]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 31 18:31:48 sekt88 volumio[724]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 31 18:31:48 sekt88 volumio[724]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 31 18:31:48 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 31 18:31:48 sekt88 volumio[724]: info: Adding Manifest REST API Endpoints Mar 31 18:31:48 sekt88 volumio[724]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 31 18:31:48 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 31 18:31:48 sekt88 volumio[724]: info: Preparing CD Folders Mar 31 18:31:48 sekt88 volumio[724]: info: Adding CD REST API Endpoints Mar 31 18:31:48 sekt88 volumio[724]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 31 18:31:48 sekt88 volumio[724]: info: Starting UDEV Watcher for CD Mar 31 18:31:49 sekt88 sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SEKT88 Mar 31 18:31:49 sekt88 sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:49 sekt88 sudo[2257]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:49 sekt88 volumio[724]: info: Detecting CD presence with UDEV Mar 31 18:31:49 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 31 18:31:49 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 31 18:31:49 sekt88 volumio[724]: info: Adding inputs REST Endpoints Mar 31 18:31:49 sekt88 volumio[724]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 31 18:31:49 sekt88 volumio[724]: info: Scanning Audio Inputs Mar 31 18:31:50 sekt88 autossh[2261]: port set to 0, monitoring disabled Mar 31 18:31:50 sekt88 autossh[2261]: starting ssh (count 1) Mar 31 18:31:50 sekt88 autossh[2261]: ssh child pid is 2267 Mar 31 18:31:50 sekt88 volumio[724]: info: Checking against Known Cards name Mar 31 18:31:50 sekt88 volumio[724]: info: Adding Server instance for streaming Mar 31 18:31:50 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 31 18:31:51 sekt88 volumio[724]: error: Hi Res Audio Failed Login: Missing Login Data Mar 31 18:31:51 sekt88 volumio[724]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 31 18:31:51 sekt88 volumio[724]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 31 18:31:51 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 31 18:31:51 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 31 18:31:51 sekt88 volumio[724]: info: Refreshing QOBUZ token Mar 31 18:31:51 sekt88 volumio[724]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 31 18:31:51 sekt88 volumio[724]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 31 18:31:51 sekt88 volumio[724]: info: Stopping AccessToken refresher cron Mar 31 18:31:52 sekt88 volumiossh-tunnel[2244]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Mar 31 18:31:52 sekt88 volumio[724]: info: AccessToken refresher cron started Mar 31 18:31:52 sekt88 volumio[724]: info: Adding TIDAL REST API Endpoints Mar 31 18:31:52 sekt88 volumio[724]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 31 18:31:53 sekt88 sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 31 18:31:53 sekt88 sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:53 sekt88 systemd[1]: Started Volumio Bluetooth Module. Mar 31 18:31:55 sekt88 sudo[2269]: pam_unix(sudo:session): session closed for user root Mar 31 18:31:57 sekt88 volumio[724]: info: Successfully Updated MyVolumio device Mar 31 18:31:57 sekt88 sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 31 18:31:57 sekt88 sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:31:58 sekt88 volumio[724]: info: Successfully Added MyVolumio device Mar 31 18:31:58 sekt88 volumiobt[2271]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 31 18:31:58 sekt88 sudo[2272]: pam_unix(sudo:session): session closed for user root Mar 31 18:32:02 sekt88 sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 31 18:32:02 sekt88 sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:32:03 sekt88 volumiobt[2271]: pulseaudio: no process found Mar 31 18:32:03 sekt88 sudo[2279]: pam_unix(sudo:session): session closed for user root Mar 31 18:32:10 sekt88 dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=2283 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 31 18:32:13 sekt88 volumio[724]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 31 18:32:14 sekt88 volumio[724]: info: Bluetooth name changed to SEKT88 Mar 31 18:32:14 sekt88 volumio[724]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 31 18:32:14 sekt88 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 31 18:32:14 sekt88 dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 31 18:32:14 sekt88 systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Successfully called chroot. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Successfully dropped privileges. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Successfully limited resources. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Running. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Canary thread running. Mar 31 18:32:14 sekt88 rtkit-daemon[2284]: Watchdog thread running. Mar 31 18:32:14 sekt88 dbus-daemon[428]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=2284 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 31 18:32:14 sekt88 systemd[1]: Starting Authorization Manager... Mar 31 18:32:15 sekt88 volumio[724]: info: MPD Permissions set Mar 31 18:32:15 sekt88 systemd[1]: mpd.service: Succeeded. Mar 31 18:32:15 sekt88 systemd[1]: Stopped Music Player Daemon. Mar 31 18:32:15 sekt88 systemd[1]: Starting Music Player Daemon... Mar 31 18:32:15 sekt88 volumio[724]: info: Remote SSH Started Mar 31 18:32:15 sekt88 polkitd[2287]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 31 18:32:15 sekt88 dbus-daemon[428]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 31 18:32:15 sekt88 systemd[1]: Started Authorization Manager. Mar 31 18:32:15 sekt88 sudo[2291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 31 18:32:15 sekt88 sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 18:32:15 sekt88 sudo[2291]: pam_unix(sudo:session): session closed for user root Mar 31 18:32:16 sekt88 pulseaudio[2283]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 31 18:32:16 sekt88 pulseaudio[2283]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 31 18:32:16 sekt88 pulseaudio[2283]: 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 Mar 31 18:32:16 sekt88 bluetoothd[569]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Mar 31 18:32:16 sekt88 bluetoothd[569]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Mar 31 18:32:16 sekt88 volumiobt[2271]: Applying permissions Mar 31 18:32:16 sekt88 volumiobt[2271]: Setting BT discoverable and pairable Mar 31 18:32:17 sekt88 kernel: Bluetooth: RFCOMM TTY layer initialized Mar 31 18:32:17 sekt88 kernel: Bluetooth: RFCOMM socket layer initialized Mar 31 18:32:17 sekt88 kernel: Bluetooth: RFCOMM ver 1.11 Mar 31 18:32:17 sekt88 volumiobt[2271]: [CHG] Controller D8:3A:DD:32:9F:95 Class: 0x0008041c Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [91B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [55B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [117B blob data] Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# -e power on Mar 31 18:32:17 sekt88 volumiobt[2271]: Invalid command in menu main: -e Mar 31 18:32:17 sekt88 volumiobt[2271]: Mar 31 18:32:17 sekt88 volumiobt[2271]: Use "help" for a list of available commands in a menu. Mar 31 18:32:17 sekt88 volumiobt[2271]: Use "menu " if you want to enter any submenu. Mar 31 18:32:17 sekt88 volumiobt[2271]: Use "back" if you want to return to menu main. Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# agent on Mar 31 18:32:17 sekt88 volumiobt[2271]: Agent is already registered Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# discoverable on Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# pairable on Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# agent NoInputNoOutput Mar 31 18:32:17 sekt88 volumiobt[2271]: Agent is already registered Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# default-agent Mar 31 18:32:17 sekt88 volumiobt[2271]: [bluetooth]# quit Mar 31 18:32:17 sekt88 volumiobt[2271]: [59B blob data] Mar 31 18:32:22 sekt88 dbus-daemon[428]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30005ms) Mar 31 18:32:22 sekt88 dbus-daemon[428]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30001ms) Mar 31 18:32:28 sekt88 volumio[724]: info: camilladsp spawned new process with pid 2307, instance 1, run: true Mar 31 18:32:28 sekt88 volumio[724]: info: MRS: Getting audio outputs on start Mar 31 18:32:28 sekt88 volumio[724]: info: MRS: Requesting all other devices output Mar 31 18:32:28 sekt88 volumio[724]: error: MyVolumio Plugins failed to start in a timely fashion Mar 31 18:32:28 sekt88 volumio[724]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Mar 31 18:32:28 sekt88 volumio[724]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Mar 31 18:32:28 sekt88 volumio[724]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Mar 31 18:32:28 sekt88 volumio[724]: info: BOOT COMPLETED Mar 31 18:32:28 sekt88 mpd[2297]: Mar 31 18:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 31 18:32:28 sekt88 volumio[724]: [Metrics] CommandRouter: 148s 964.29ms Mar 31 18:32:28 sekt88 volumio[724]: info: CoreCommandRouter::volumiosetStartupVolume Mar 31 18:32:28 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 18:32:28 sekt88 volumio[724]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 18:32:29 sekt88 volumio[724]: info: CoreCommandRouter::Close All Modals sent Mar 31 18:32:29 sekt88 volumio[724]: info: CoreCommandRouter::Close All Modals sent Mar 31 18:32:30 sekt88 systemd[1]: Started Music Player Daemon. Mar 31 18:32:32 sekt88 volumio[724]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 18:32:33 sekt88 volumio[724]: Error: write EPIPE Mar 31 18:32:33 sekt88 volumio[724]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) Mar 31 18:32:33 sekt88 volumio[724]: at writeGeneric (internal/stream_base_commons.js:147:3) Mar 31 18:32:33 sekt88 volumio[724]: at Socket._writeGeneric (net.js:785:11) Mar 31 18:32:33 sekt88 volumio[724]: at Socket._write (net.js:797:8) Mar 31 18:32:33 sekt88 volumio[724]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 31 18:32:33 sekt88 volumio[724]: at Socket.Writable.write (internal/streams/writable.js:303:10) Mar 31 18:32:33 sekt88 volumio[724]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16 Mar 31 18:32:33 sekt88 volumio[724]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11) Mar 31 18:32:33 sekt88 volumio[724]: at Socket.emit (events.js:315:20) Mar 31 18:32:33 sekt88 volumio[724]: at emitReadable_ (internal/streams/readable.js:569:12) Mar 31 18:32:33 sekt88 volumio[724]: at processTicksAndRejections (internal/process/task_queues.js:79:21) { Mar 31 18:32:33 sekt88 volumio[724]: errno: -32, Mar 31 18:32:33 sekt88 volumio[724]: code: 'EPIPE', Mar 31 18:32:33 sekt88 volumio[724]: syscall: 'write' Mar 31 18:32:33 sekt88 volumio[724]: } Mar 31 18:32:33 sekt88 volumio[724]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 18:32:33 sekt88 sudo[2248]: pam_unix(sudo:session): session closed for user root Mar 31 18:32:36 sekt88 sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 18:31 Mar 31 18:32:36 sekt88 sudo[2329]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"