-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-12-01 16:29:26 CET. -- Dec 01 16:28:01 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 16:28:01 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:01 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 16:28:02 p2aes volumio-remote-updater[578]: Test mode enabled Dec 01 16:28:02 p2aes volumio-remote-updater[578]: No alpha test mode Dec 01 16:28:02 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 01 16:28:03 p2aes volumio[917]: info: Setting Geolocation for MyVolumio to eu4 Dec 01 16:28:03 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:03 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:28:03 p2aes volumio[917]: info: Setting Geolocation for MyVolumio to eu6 Dec 01 16:28:03 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:03 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:03 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:04 p2aes volumio[917]: info: Remote config written successfully Dec 01 16:28:04 p2aes volumio[917]: info: Starting Tunnel 1 Dec 01 16:28:04 p2aes volumio[917]: info: Starting Tunnel Connection Checker Dec 01 16:28:04 p2aes volumio[917]: info: Completed starting MyVolumio Plugin Dec 01 16:28:04 p2aes volumio[917]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 01 16:28:04 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 01 16:28:04 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 01 16:28:04 p2aes volumio[917]: verbose: New Socket.io Connection to 192.168.0.152:3000 from 192.168.0.117 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 01 16:28:04 p2aes volumio[917]: verbose: New Socket.io Connection to 192.168.0.152:3000 from 192.168.0.117 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 01 16:28:04 p2aes volumio[917]: info: Updating MyVolumio device info Dec 01 16:28:04 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:04 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:05 p2aes volumio[917]: info: MYVolumio Device enabled Dec 01 16:28:05 p2aes volumio[917]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 01 16:28:05 p2aes volumio[917]: info: MyVolumio status changed Dec 01 16:28:05 p2aes volumio[917]: info: Streaming services startup Dec 01 16:28:05 p2aes volumio[917]: info: Starting Streaming Daemon Dec 01 16:28:05 p2aes volumio[917]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 01 16:28:05 p2aes sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 01 16:28:05 p2aes sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:05 p2aes volumio[917]: info: Updating MyVolumio device info Dec 01 16:28:05 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:05 p2aes sudo[1595]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:06 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 01 16:28:06 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 01 16:28:07 p2aes volumio[917]: info: Successfully Added MyVolumio device Dec 01 16:28:07 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 01 16:28:07 p2aes volumio[917]: error: Cannot start Volumio Streaming Daemon Dec 01 16:28:07 p2aes volumio[917]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 01 16:28:07 p2aes volumio[917]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 16:28:07 p2aes volumio[917]: info: Successfully Added MyVolumio device Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:07 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:07 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:07 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:08 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 01 16:28:08 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 01 16:28:08 p2aes volumio[917]: info: [1733066888647] Starting BluetoothController Dec 01 16:28:08 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 01 16:28:11 p2aes sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 01 16:28:11 p2aes sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:11 p2aes sudo[1642]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:11 p2aes volumio[917]: info: MRS: MultiRoom plugin initialized Dec 01 16:28:11 p2aes volumio[917]: info: MRS: STOPPING SNAPCLIENT Dec 01 16:28:11 p2aes volumio[917]: info: MRS: Snap server stop Dec 01 16:28:11 p2aes volumio[917]: info: MRS: STOPPING volumioStreaming Dec 01 16:28:11 p2aes sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 01 16:28:11 p2aes sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:11 p2aes sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 01 16:28:11 p2aes sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:11 p2aes sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 01 16:28:11 p2aes sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 01 16:28:11 p2aes sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 01 16:28:11 p2aes sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:11 p2aes sudo[1666]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:11 p2aes sudo[1664]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:11 p2aes sudo[1660]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:11 p2aes sudo[1658]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 01 16:28:11 p2aes volumio[917]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 01 16:28:11 p2aes volumio[917]: info: Preparing to generate the ALSA configuration file Dec 01 16:28:12 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:12 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:14 p2aes volumio[917]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 01 16:28:14 p2aes volumio[917]: info: Reading ALSA contributions from plugins. Dec 01 16:28:14 p2aes volumio[917]: info: Successfully Updated MyVolumio device Dec 01 16:28:14 p2aes volumio[917]: info: MRS: Removed streaming files Dec 01 16:28:14 p2aes volumio[917]: info: MRS: volumioStreaming STOPPED Dec 01 16:28:14 p2aes volumio[917]: info: MRS: SNAPSERVER STOPPED Dec 01 16:28:14 p2aes volumio[917]: info: MRS: SNAPCLIENT STOPPED Dec 01 16:28:15 p2aes volumio[917]: info: Successfully Updated MyVolumio device Dec 01 16:28:15 p2aes ntpd[747]: Soliciting pool server 195.186.1.100 Dec 01 16:28:15 p2aes ntpd[747]: Soliciting pool server 195.186.1.101 Dec 01 16:28:16 p2aes ntpd[747]: Soliciting pool server 212.51.144.46 Dec 01 16:28:16 p2aes ntpd[747]: Soliciting pool server 84.73.226.160 Dec 01 16:28:16 p2aes ntpd[747]: Soliciting pool server 195.176.26.204 Dec 01 16:28:17 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 01 16:28:17 p2aes ntpd[747]: Soliciting pool server 217.147.208.1 Dec 01 16:28:17 p2aes ntpd[747]: Soliciting pool server 185.17.70.106 Dec 01 16:28:17 p2aes ntpd[747]: Soliciting pool server 62.12.167.109 Dec 01 16:28:18 p2aes sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 01 16:28:18 p2aes sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:18 p2aes systemd[1]: Started MyVolumio SSH Tunnel. Dec 01 16:28:18 p2aes sudo[1691]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:18 p2aes autossh[1694]: port set to 0, monitoring disabled Dec 01 16:28:18 p2aes autossh[1694]: starting ssh (count 1) Dec 01 16:28:18 p2aes autossh[1694]: ssh child pid is 1697 Dec 01 16:28:18 p2aes ntpd[747]: Soliciting pool server 81.94.123.17 Dec 01 16:28:18 p2aes ntpd[747]: Soliciting pool server 85.195.224.28 Dec 01 16:28:18 p2aes ntpd[747]: Soliciting pool server 84.72.108.37 Dec 01 16:28:18 p2aes ntpd[747]: Soliciting pool server 83.150.25.25 Dec 01 16:28:19 p2aes volumiossh-tunnel[1693]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Dec 01 16:28:19 p2aes ntpd[747]: Soliciting pool server 91.235.212.22 Dec 01 16:28:19 p2aes ntpd[747]: Soliciting pool server 2001:8a8:1005:1::11 Dec 01 16:28:19 p2aes ntpd[747]: Soliciting pool server 109.233.182.115 Dec 01 16:28:21 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 01 16:28:21 p2aes volumio[917]: info: Remote SSH Started Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db77ee82 does not match aorg 0000000000.00000000 from server@81.94.123.17 xmt 0xeaf6ff19.ee1be81c Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db6d02fe does not match aorg 0000000000.00000000 from server@109.233.182.115 xmt 0xeaf6ff19.ee1a10e6 Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db79804f does not match aorg 0000000000.00000000 from server@185.17.70.106 xmt 0xeaf6ff19.ed9b1de1 Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db7e880b does not match aorg 0000000000.00000000 from server@195.186.1.101 xmt 0xeaf6ff19.efd74916 Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db7d7704 does not match aorg 0000000000.00000000 from server@195.186.1.100 xmt 0xeaf6ff19.efd7817f Dec 01 16:28:25 p2aes ntpd[747]: receive: Unexpected origin timestamp 0xeaf6ff18.db7c4474 does not match aorg 0000000000.00000000 from server@212.51.144.46 xmt 0xeaf6ff19.ef230620 Dec 01 16:28:27 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Dec 01 16:28:28 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Dec 01 16:28:28 p2aes volumio[917]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Dec 01 16:28:29 p2aes volumio[917]: info: Asound.conf file written Dec 01 16:28:29 p2aes sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 01 16:28:29 p2aes sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:29 p2aes sudo[1716]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:30 p2aes volumio[917]: info: Output device has changed, restarting MPD Dec 01 16:28:30 p2aes sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 16:28:30 p2aes sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:30 p2aes sudo[1721]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:30 p2aes volumio[917]: info: Output device has changed, restarting Shairport Sync Dec 01 16:28:30 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:30 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:30 p2aes sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 16:28:30 p2aes sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:30 p2aes systemd[1]: Stopping Music Player Daemon... Dec 01 16:28:30 p2aes volumio[917]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 16:28:30 p2aes volumio[917]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 01 16:28:30 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:30 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 01 16:28:30 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 16:28:30 p2aes sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name P2AES Dec 01 16:28:30 p2aes sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:30 p2aes sudo[1732]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:30 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 01 16:28:30 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 01 16:28:30 p2aes volumio[917]: info: MRS: Pushing multiroomSync output for this device Dec 01 16:28:30 p2aes volumio[917]: info: MRS: Pushing multiroomSync output Dec 01 16:28:30 p2aes volumio[917]: info: Adding audio output: Dec 01 16:28:30 p2aes volumio[917]: info: Adding audio output: Dec 01 16:28:31 p2aes volumio[917]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 01 16:28:31 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 01 16:28:31 p2aes volumio[917]: info: Adding METAVOLUMIO REST API Endpoints Dec 01 16:28:31 p2aes volumio[917]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 01 16:28:31 p2aes volumio[917]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 01 16:28:31 p2aes volumio[917]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 01 16:28:31 p2aes volumio[917]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 01 16:28:31 p2aes volumio[917]: info: Preparing CD Folders Dec 01 16:28:31 p2aes volumio[917]: info: Adding CD REST API Endpoints Dec 01 16:28:31 p2aes volumio[917]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 01 16:28:31 p2aes volumio[917]: info: Starting UDEV Watcher for CD Dec 01 16:28:31 p2aes volumio[917]: info: Detecting CD presence with UDEV Dec 01 16:28:31 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 01 16:28:31 p2aes volumio[917]: info: Adding inputs REST Endpoints Dec 01 16:28:31 p2aes volumio[917]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 01 16:28:31 p2aes volumio[917]: info: Scanning Audio Inputs Dec 01 16:28:31 p2aes volumio[917]: info: Checking against Known Cards name Dec 01 16:28:31 p2aes volumio[917]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 16:28:31 p2aes volumio[917]: info: [1733066911738] CoreMusicLibrary::Adding element HiFiBerry ADC Dec 01 16:28:31 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:28:31 p2aes volumio[917]: Cannot find translation for source HiFiBerry ADC Dec 01 16:28:31 p2aes volumio[917]: info: Checking against Known Cards name Dec 01 16:28:31 p2aes volumio[917]: info: Adding Server instance for streaming Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 01 16:28:31 p2aes volumio[917]: error: Hi Res Audio Failed Login: Missing Login Data Dec 01 16:28:31 p2aes volumio[917]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 01 16:28:31 p2aes volumio[917]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 01 16:28:31 p2aes volumio[917]: info: Refreshing TIDAL token Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 01 16:28:31 p2aes volumio[917]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 01 16:28:32 p2aes sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 01 16:28:32 p2aes sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:32 p2aes volumio[917]: info: Stopping AccessToken refresher cron for QOBUZ Dec 01 16:28:32 p2aes sudo[1740]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:32 p2aes volumio[917]: info: AccessToken refresher cron started for QOBUZ Dec 01 16:28:32 p2aes volumio[917]: info: Adding QOBUZ REST API Endpoints Dec 01 16:28:32 p2aes volumio[917]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 01 16:28:33 p2aes volumio[917]: info: MRS: Adding multiroomSync output Dec 01 16:28:33 p2aes volumio[917]: info: Adding audio output: Dec 01 16:28:33 p2aes volumio[917]: info: MRS: Pushing multiroomSync output Dec 01 16:28:33 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Dec 01 16:28:33 p2aes volumio[917]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Dec 01 16:28:33 p2aes volumio[917]: info: Bluetooth name changed to P2AES Dec 01 16:28:33 p2aes volumio[917]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Dec 01 16:28:33 p2aes volumio[917]: info: MPD Permissions set Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:33 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:33 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:34 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:34 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:34 p2aes systemd[1]: mpd.service: Succeeded. Dec 01 16:28:34 p2aes systemd[1]: Stopped Music Player Daemon. Dec 01 16:28:34 p2aes systemd[1]: Starting Music Player Daemon... Dec 01 16:28:34 p2aes sudo[1763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 01 16:28:34 p2aes sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:34 p2aes volumio[917]: info: Starting Shairport Sync Dec 01 16:28:34 p2aes systemd[1]: Started Volumio Bluetooth Module. Dec 01 16:28:34 p2aes sudo[1761]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 16:28:34 p2aes sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:34 p2aes sudo[1761]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:34 p2aes sudo[1763]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:34 p2aes sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 01 16:28:34 p2aes sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:34 p2aes sudo[1768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 16:28:34 p2aes volumiobt[1765]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 01 16:28:34 p2aes sudo[1768]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:34 p2aes sudo[1769]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:34 p2aes systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 16:28:34 p2aes systemd[1]: shairport-sync.service: Succeeded. Dec 01 16:28:34 p2aes systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 16:28:34 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 01 16:28:34 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 01 16:28:34 p2aes volumio[917]: info: Access Token successfully retrieved Dec 01 16:28:34 p2aes systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 16:28:34 p2aes sudo[1768]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:34 p2aes volumio[917]: info: Volumio BT Module successfully started Dec 01 16:28:34 p2aes volumio[917]: info: Shairport-Sync Started Dec 01 16:28:35 p2aes volumio[917]: info: Successfully retrieved User Session From TIDAL Dec 01 16:28:35 p2aes volumio[917]: info: TidalConnect service stoped! Dec 01 16:28:35 p2aes volumio[917]: error: MyVolumio Plugins failed to start in a timely fashion Dec 01 16:28:35 p2aes volumio[917]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Dec 01 16:28:35 p2aes volumio[917]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Dec 01 16:28:35 p2aes volumio[917]: [Metrics] CommandRouter: 75s 780.16ms Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::volumiosetStartupVolume Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::Close All Modals sent Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::Close All Modals sent Dec 01 16:28:35 p2aes volumio[917]: info: Successfully retrieved User Subscription From TIDAL Dec 01 16:28:35 p2aes volumio[917]: info: Adding TIDAL to Browse Sources Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 16:28:35 p2aes volumio[917]: info: [1733066915562] CoreMusicLibrary::Adding element TIDAL Dec 01 16:28:35 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:28:35 p2aes volumio[917]: Cannot find translation for source HiFiBerry ADC Dec 01 16:28:35 p2aes volumio[917]: Cannot find translation for source TIDAL Dec 01 16:28:35 p2aes sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 01 16:28:35 p2aes volumio[917]: info: Stopping AccessToken refresher cron Dec 01 16:28:35 p2aes sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:35 p2aes volumio[917]: info: AccessToken refresher cron started Dec 01 16:28:35 p2aes volumio[917]: info: Adding TIDAL REST API Endpoints Dec 01 16:28:35 p2aes volumio[917]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 01 16:28:35 p2aes volumiobt[1765]: pulseaudio: no process found Dec 01 16:28:35 p2aes sudo[1787]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:35 p2aes volumio[917]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 01 16:28:35 p2aes volumio[917]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 01 16:28:35 p2aes volumio[917]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 01 16:28:35 p2aes volumio[917]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Dec 01 16:28:35 p2aes volumio[917]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Dec 01 16:28:35 p2aes volumio[917]: aplay: main:828: audio open error: No such device Dec 01 16:28:35 p2aes sudo[1791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 01 16:28:35 p2aes sudo[1791]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:35 p2aes dbus-daemon[546]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=1794 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 01 16:28:35 p2aes systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 01 16:28:35 p2aes systemd[1]: Started Volumio Tidal Connect Service. Dec 01 16:28:35 p2aes sudo[1791]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:35 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 01 16:28:35 p2aes dbus-daemon[546]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 01 16:28:35 p2aes systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Successfully called chroot. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Successfully dropped privileges. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Successfully limited resources. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Running. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Watchdog thread running. Dec 01 16:28:35 p2aes rtkit-daemon[1795]: Canary thread running. Dec 01 16:28:35 p2aes dbus-daemon[546]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=1795 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 01 16:28:36 p2aes systemd[1]: Starting Authorization Manager... Dec 01 16:28:36 p2aes volumio[917]: info: MRS: Getting audio outputs on start Dec 01 16:28:36 p2aes volumio[917]: info: MRS: Requesting all other devices output Dec 01 16:28:36 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 01 16:28:36 p2aes polkitd[1802]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 01 16:28:36 p2aes dbus-daemon[546]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 01 16:28:36 p2aes systemd[1]: Started Authorization Manager. Dec 01 16:28:36 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 01 16:28:36 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 16:28:36 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 01 16:28:36 p2aes volumio[917]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Dec 01 16:28:36 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 16:28:37 p2aes volumio[917]: info: Executing endpoint tc_getconfig Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 01 16:28:37 p2aes vtcs[1796]: STARTING TidalConnect services, version: 1.4.0.34 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 16:28:37 p2aes volumio[917]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 01 16:28:37 p2aes volumio[917]: info: Received Get System Info Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:28:37 p2aes volumio[917]: info: Discovery: Getting this device information Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: Listing playlists Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:28:37 p2aes volumio[917]: info: Received Get System Info Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:28:37 p2aes volumio[917]: info: Discovery: Getting this device information Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:28:37 p2aes volumio[917]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 01 16:28:37 p2aes volumio[917]: info: Received Get System Version Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 16:28:37 p2aes volumio[917]: info: Received Get System Info Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:28:37 p2aes volumio[917]: info: Discovery: Getting this device information Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 01 16:28:37 p2aes pulseaudio[1794]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 01 16:28:37 p2aes pulseaudio[1794]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 01 16:28:37 p2aes volumiobt[1765]: Applying permissions Dec 01 16:28:37 p2aes volumiobt[1765]: Setting BT discoverable and pairable Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 16:28:37 p2aes volumio[917]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 01 16:28:37 p2aes volumio[917]: info: Received Get System Info Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:28:37 p2aes volumio[917]: info: Discovery: Getting this device information Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes bluetoothd[749]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource Dec 01 16:28:37 p2aes bluetoothd[749]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink Dec 01 16:28:37 p2aes pulseaudio[1794]: 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 Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: Listing playlists Dec 01 16:28:37 p2aes vtcs[1796]: STARTED TidalConnect services. Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 01 16:28:37 p2aes kernel: Bluetooth: RFCOMM TTY layer initialized Dec 01 16:28:37 p2aes kernel: Bluetooth: RFCOMM socket layer initialized Dec 01 16:28:37 p2aes kernel: Bluetooth: RFCOMM ver 1.11 Dec 01 16:28:37 p2aes volumio[917]: info: Executing endpoint tc_connect Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 01 16:28:37 p2aes volumiobt[1765]: [CHG] Controller B8:27:EB:DE:5F:F6 Class: 0x0008041c Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [91B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [55B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumio[917]: info: Connecting to TidalConnect Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [117B blob data] Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# -e power on Dec 01 16:28:37 p2aes volumiobt[1765]: Invalid command in menu main: -e Dec 01 16:28:37 p2aes volumiobt[1765]: Dec 01 16:28:37 p2aes volumiobt[1765]: Use "help" for a list of available commands in a menu. Dec 01 16:28:37 p2aes volumiobt[1765]: Use "menu " if you want to enter any submenu. Dec 01 16:28:37 p2aes volumiobt[1765]: Use "back" if you want to return to menu main. Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# agent on Dec 01 16:28:37 p2aes volumiobt[1765]: Agent is already registered Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# discoverable on Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# pairable on Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# agent NoInputNoOutput Dec 01 16:28:37 p2aes volumiobt[1765]: Agent is already registered Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# default-agent Dec 01 16:28:37 p2aes volumiobt[1765]: [bluetooth]# quit Dec 01 16:28:37 p2aes volumiobt[1765]: [59B blob data] Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::servicePushState Dec 01 16:28:37 p2aes volumio[917]: info: CoreStateMachine::pushState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioPushState Dec 01 16:28:37 p2aes volumio[917]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:28:37 p2aes volumio[917]: info: MRS: Pushing multiroomSync output Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::servicePushState Dec 01 16:28:37 p2aes volumio[917]: info: CoreStateMachine::pushState Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: CoreCommandRouter::volumioPushState Dec 01 16:28:37 p2aes volumio[917]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:28:37 p2aes volumio[917]: info: MRS: Pushing multiroomSync output Dec 01 16:28:37 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:37 p2aes volumio[917]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 01 16:28:38 p2aes systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 01 16:28:38 p2aes systemd[1]: mpd.service: Failed with result 'protocol'. Dec 01 16:28:38 p2aes systemd[1]: Failed to start Music Player Daemon. Dec 01 16:28:38 p2aes sudo[1724]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:38 p2aes volumio[917]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 01 16:28:38 p2aes volumio[917]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 01 16:28:38 p2aes volumio[917]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 01 16:28:38 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 01 16:28:38 p2aes systemd[1]: Starting Music Player Daemon... Dec 01 16:28:38 p2aes volumio[917]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 01 16:28:38 p2aes sudo[1832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 16:28:38 p2aes sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:28:38 p2aes sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 01 16:28:38 p2aes volumio[917]: info: TidalConnect service started! Dec 01 16:28:39 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 01 16:28:40 p2aes mpd[1834]: Dec 01 16:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 16:28:42 p2aes systemd[1]: Started Music Player Daemon. Dec 01 16:28:42 p2aes mpd_monitor.sh[568]: MPD restarted due to no mpc output. Dec 01 16:28:42 p2aes volumio[917]: error: updateQueue error: null Dec 01 16:28:42 p2aes volumio[917]: info: BOOT COMPLETED Dec 01 16:28:47 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:28:47 p2aes volumio[917]: info: Received Get System Info Dec 01 16:28:47 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:28:47 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:28:47 p2aes volumio[917]: info: Discovery: Getting this device information Dec 01 16:28:47 p2aes volumio[917]: info: CoreCommandRouter::volumioGetState Dec 01 16:28:47 p2aes volumio[917]: info: CorePlayQueue::getTrack 0 Dec 01 16:28:47 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:28:59 p2aes volumio[917]: info: CoreCommandRouter::volumioGetQueue Dec 01 16:28:59 p2aes volumio[917]: info: CoreStateMachine::getQueue Dec 01 16:28:59 p2aes volumio[917]: info: CorePlayQueue::getQueue Dec 01 16:29:01 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 16:29:01 p2aes volumio[917]: info: CURURI: artists:// Dec 01 16:29:01 p2aes volumio[917]: info: Preload queue cleared Dec 01 16:29:13 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 16:29:13 p2aes volumio[917]: info: Preload queue cleared Dec 01 16:29:17 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 16:29:17 p2aes volumio[917]: info: Preload queue cleared Dec 01 16:29:19 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 16:29:19 p2aes volumio[917]: info: Preload queue cleared Dec 01 16:29:23 p2aes volumio[917]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 01 16:29:23 p2aes volumio[917]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:29:23 p2aes volumio[917]: TypeError: Cannot read property 'length' of undefined Dec 01 16:29:23 p2aes volumio[917]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Dec 01 16:29:23 p2aes volumio[917]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Dec 01 16:29:23 p2aes volumio[917]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Dec 01 16:29:23 p2aes volumio[917]: at Parser.emit (events.js:315:20) Dec 01 16:29:23 p2aes volumio[917]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Dec 01 16:29:23 p2aes volumio[917]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Dec 01 16:29:23 p2aes volumio[917]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Dec 01 16:29:23 p2aes volumio[917]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Dec 01 16:29:23 p2aes volumio[917]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Dec 01 16:29:23 p2aes volumio[917]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Dec 01 16:29:23 p2aes volumio[917]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Dec 01 16:29:23 p2aes volumio[917]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Dec 01 16:29:23 p2aes volumio[917]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Dec 01 16:29:23 p2aes volumio[917]: at IncomingMessage.emit (events.js:327:22) Dec 01 16:29:23 p2aes volumio[917]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 01 16:29:23 p2aes volumio[917]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 01 16:29:23 p2aes volumio[917]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:29:26 p2aes sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 16:28 Dec 01 16:29:26 p2aes sudo[2179]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c56b231f88ce17023518c3b6aa9e933e8e8256d8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 25 Nov 2024 02:41:54 PM CET" VOLUMIO_VERSION="3.782" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="769a33e6dd61b9a5d2fa14f934eef739"