-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-03-28 22:29:20 CET. -- Mar 28 22:28:00 volumiorobi volumio[830]: info: MyVolumio token set successfully Mar 28 22:28:00 volumiorobi volumio[830]: info: MYVOLUMIO: Adding device Mar 28 22:28:00 volumiorobi volumio[830]: info: MYVOLUMIO: Evaluating Server Mar 28 22:28:00 volumiorobi volumio[830]: info: MyVolumio Plan changed: premium Mar 28 22:28:00 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 28 22:28:00 volumiorobi volumio[830]: info: Removing browser output: myVolumio user plan is not superstar Mar 28 22:28:00 volumiorobi volumio[830]: info: Removing audio output: Mar 28 22:28:00 volumiorobi volumio[830]: info: MYVOLUMIO: Adding device Mar 28 22:28:00 volumiorobi volumio[830]: info: MYVOLUMIO: Evaluating Server Mar 28 22:28:00 volumiorobi volumio[830]: info: Remote config written successfully Mar 28 22:28:00 volumiorobi volumio[830]: info: Starting Tunnel 1 Mar 28 22:28:00 volumiorobi volumio[830]: info: Starting Tunnel Connection Checker Mar 28 22:28:00 volumiorobi volumio[830]: info: Completed starting MyVolumio Plugin Mar 28 22:28:00 volumiorobi volumio[830]: STREAMING PROXY: Starting server on port 3245 Mar 28 22:28:00 volumiorobi volumio[830]: Node JS runtime: 14 Mar 28 22:28:00 volumiorobi volumio[830]: info: MYVolumio Device enabled Mar 28 22:28:00 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 28 22:28:00 volumiorobi volumio[830]: info: MyVolumio status changed Mar 28 22:28:00 volumiorobi volumio[830]: info: Streaming services startup Mar 28 22:28:00 volumiorobi volumio[830]: info: Starting Streaming Daemon Mar 28 22:28:00 volumiorobi sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 22:28:00 volumiorobi volumio[830]: info: Setting Geolocation for MyVolumio to eu3 Mar 28 22:28:00 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:00 volumiorobi sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:00 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:00 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:01 volumiorobi sudo[1126]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:01 volumiorobi volumio[830]: error: Cannot start Volumio Streaming Daemon Mar 28 22:28:01 volumiorobi volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 28 22:28:01 volumiorobi volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 28 22:28:01 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 28 22:28:01 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:01 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 28 22:28:01 volumiorobi volumio[830]: info: [1711661281239] Starting BluetoothController Mar 28 22:28:01 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 28 22:28:03 volumiorobi sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 28 22:28:03 volumiorobi sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi sudo[1132]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: MultiRoom plugin initialized Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: STOPPING SNAPCLIENT Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: Snap server stop Mar 28 22:28:03 volumiorobi sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 28 22:28:03 volumiorobi sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: STOPPING volumioStreaming Mar 28 22:28:03 volumiorobi sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 28 22:28:03 volumiorobi sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 28 22:28:03 volumiorobi sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 28 22:28:03 volumiorobi sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi sudo[1148]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 28 22:28:03 volumiorobi sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi sudo[1156]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi sudo[1150]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 28 22:28:03 volumiorobi sudo[1154]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 28 22:28:03 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 28 22:28:03 volumiorobi volumio[830]: info: Preparing to generate the ALSA configuration file Mar 28 22:28:03 volumiorobi volumio[830]: info: Updating MyVolumio device info Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 22:28:03 volumiorobi volumio[830]: info: Reading ALSA contributions from plugins. Mar 28 22:28:03 volumiorobi volumio[830]: info: Setting Geolocation for MyVolumio to eu4 Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: Removed streaming files Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: volumioStreaming STOPPED Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: SNAPSERVER STOPPED Mar 28 22:28:03 volumiorobi volumio[830]: info: MRS: SNAPCLIENT STOPPED Mar 28 22:28:03 volumiorobi volumio[830]: info: Asound.conf file written Mar 28 22:28:03 volumiorobi sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 22:28:03 volumiorobi sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi volumio[830]: info: Output device has changed, restarting MPD Mar 28 22:28:03 volumiorobi volumio[830]: info: Output device has changed, restarting Shairport Sync Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:03 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:03 volumiorobi sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 22:28:03 volumiorobi sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:03 volumiorobi sudo[1165]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:03 volumiorobi sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 22:28:03 volumiorobi sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi systemd[1]: Stopping Music Player Daemon... Mar 28 22:28:04 volumiorobi volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 22:28:04 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 28 22:28:04 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output for this device Mar 28 22:28:04 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding audio output: Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding audio output: Mar 28 22:28:04 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:28:04 volumiorobi sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio_robi Mar 28 22:28:04 volumiorobi sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding METAVOLUMIO REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 28 22:28:04 volumiorobi sudo[1176]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding Manifest REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 28 22:28:04 volumiorobi volumio[830]: info: Preparing CD Folders Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding CD REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 28 22:28:04 volumiorobi volumio[830]: info: Starting UDEV Watcher for CD Mar 28 22:28:04 volumiorobi volumio[830]: info: Detecting CD presence with UDEV Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 28 22:28:04 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding inputs REST Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 28 22:28:04 volumiorobi volumio[830]: info: Scanning Audio Inputs Mar 28 22:28:04 volumiorobi volumio[830]: info: Checking against Known Cards name Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding Server instance for streaming Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 28 22:28:04 volumiorobi volumio[830]: error: Hi Res Audio Failed Login: Missing Login Data Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 28 22:28:04 volumiorobi volumio[830]: info: Refreshing QOBUZ token Mar 28 22:28:04 volumiorobi volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 28 22:28:04 volumiorobi volumio[830]: info: Stopping AccessToken refresher cron Mar 28 22:28:04 volumiorobi systemd[1]: mpd.service: Succeeded. Mar 28 22:28:04 volumiorobi systemd[1]: Stopped Music Player Daemon. Mar 28 22:28:04 volumiorobi sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 22:28:04 volumiorobi sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi volumio[830]: info: AccessToken refresher cron started Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding TIDAL REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 28 22:28:04 volumiorobi systemd[1]: Starting Music Player Daemon... Mar 28 22:28:04 volumiorobi sudo[1185]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:04 volumiorobi volumio[830]: info: Successfully Added MyVolumio device Mar 28 22:28:04 volumiorobi volumio[830]: info: Bluetooth name changed to Volumio_robi Mar 28 22:28:04 volumiorobi volumio[830]: info: MPD Permissions set Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:28:04 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: Starting Shairport Sync Mar 28 22:28:04 volumiorobi volumio[830]: info: Preparing to generate the ALSA configuration file Mar 28 22:28:04 volumiorobi sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 22:28:04 volumiorobi sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi volumio[830]: info: Access Token successfully retrieved Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 22:28:04 volumiorobi volumio[830]: info: [1711661284716] CoreMusicLibrary::Adding element QOBUZ Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 22:28:04 volumiorobi volumio[830]: Cannot find translation for source Radio Paradise Mar 28 22:28:04 volumiorobi volumio[830]: Cannot find translation for source QOBUZ Mar 28 22:28:04 volumiorobi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 22:28:04 volumiorobi systemd[1]: shairport-sync.service: Succeeded. Mar 28 22:28:04 volumiorobi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:04 volumiorobi volumio[830]: info: Stopping AccessToken refresher cron for QOBUZ Mar 28 22:28:04 volumiorobi volumio[830]: info: AccessToken refresher cron started for QOBUZ Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding QOBUZ REST API Endpoints Mar 28 22:28:04 volumiorobi volumio[830]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 28 22:28:04 volumiorobi volumio[830]: info: Updating MyVolumio device info Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:04 volumiorobi sudo[1194]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:04 volumiorobi volumio[830]: info: Shairport-Sync Started Mar 28 22:28:04 volumiorobi volumio[830]: info: Asound.conf file written Mar 28 22:28:04 volumiorobi sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 22:28:04 volumiorobi sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi sudo[1199]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:04 volumiorobi volumio[830]: info: Output device has changed, restarting MPD Mar 28 22:28:04 volumiorobi volumio[830]: info: Output device has changed, restarting Shairport Sync Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:04 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:04 volumiorobi sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 22:28:04 volumiorobi sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:04 volumiorobi sudo[1205]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:04 volumiorobi sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 22:28:04 volumiorobi sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 28 22:28:05 volumiorobi systemd[1]: mpd.service: Succeeded. Mar 28 22:28:05 volumiorobi systemd[1]: Stopped Music Player Daemon. Mar 28 22:28:05 volumiorobi volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 22:28:05 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: Preparing to generate the ALSA configuration file Mar 28 22:28:05 volumiorobi systemd[1]: Starting Music Player Daemon... Mar 28 22:28:05 volumiorobi volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 22:28:05 volumiorobi volumio[830]: info: Reading ALSA contributions from plugins. Mar 28 22:28:05 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:28:05 volumiorobi volumio[830]: info: Successfully Updated MyVolumio device Mar 28 22:28:05 volumiorobi volumio[830]: info: Successfully Added MyVolumio device Mar 28 22:28:05 volumiorobi volumio[830]: info: MPD Permissions set Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: Starting Shairport Sync Mar 28 22:28:05 volumiorobi volumio[830]: info: Asound.conf file written Mar 28 22:28:05 volumiorobi sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 22:28:05 volumiorobi sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 22:28:05 volumiorobi systemd[1]: shairport-sync.service: Succeeded. Mar 28 22:28:05 volumiorobi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:05 volumiorobi sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 22:28:05 volumiorobi sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi sudo[1224]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:05 volumiorobi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:05 volumiorobi sudo[1221]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:05 volumiorobi volumio[830]: info: Output device has changed, restarting MPD Mar 28 22:28:05 volumiorobi volumio[830]: info: Output device has changed, restarting Shairport Sync Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:05 volumiorobi sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 22:28:05 volumiorobi sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi sudo[1232]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:05 volumiorobi sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 22:28:05 volumiorobi sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 28 22:28:05 volumiorobi systemd[1]: mpd.service: Succeeded. Mar 28 22:28:05 volumiorobi systemd[1]: Stopped Music Player Daemon. Mar 28 22:28:05 volumiorobi volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 22:28:05 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi systemd[1]: Starting Music Player Daemon... Mar 28 22:28:05 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:28:05 volumiorobi volumio[830]: info: Successfully Updated MyVolumio device Mar 28 22:28:05 volumiorobi volumio[830]: info: MPD Permissions set Mar 28 22:28:05 volumiorobi volumio[830]: info: Shairport-Sync Started Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:05 volumiorobi volumio[830]: info: Starting Shairport Sync Mar 28 22:28:05 volumiorobi sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 22:28:05 volumiorobi sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 22:28:05 volumiorobi systemd[1]: shairport-sync.service: Succeeded. Mar 28 22:28:05 volumiorobi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:05 volumiorobi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 22:28:05 volumiorobi sudo[1248]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:05 volumiorobi volumio[830]: info: Shairport-Sync Started Mar 28 22:28:05 volumiorobi sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 28 22:28:05 volumiorobi sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:05 volumiorobi systemd[1]: Started MyVolumio SSH Tunnel. Mar 28 22:28:05 volumiorobi sudo[1258]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:05 volumiorobi volumio[830]: info: Remote SSH Started Mar 28 22:28:05 volumiorobi autossh[1261]: port set to 0, monitoring disabled Mar 28 22:28:05 volumiorobi autossh[1261]: starting ssh (count 1) Mar 28 22:28:05 volumiorobi autossh[1261]: ssh child pid is 1264 Mar 28 22:28:06 volumiorobi volumiossh-tunnel[1260]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Mar 28 22:28:06 volumiorobi volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Mar 28 22:28:06 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:28:06 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:06 volumiorobi mpd[1240]: Mar 28 22:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 22:28:06 volumiorobi systemd[1]: Started Music Player Daemon. Mar 28 22:28:06 volumiorobi sudo[1167]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:06 volumiorobi sudo[1207]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:06 volumiorobi sudo[1234]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:06 volumiorobi volumio[830]: error: MPD error: The expression evaluated to a falsy value: Mar 28 22:28:06 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:28:06 volumiorobi volumio[830]: error: The expression evaluated to a falsy value: Mar 28 22:28:06 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:28:06 volumiorobi volumio[830]: error: MPD error: The expression evaluated to a falsy value: Mar 28 22:28:06 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:28:06 volumiorobi volumio[830]: error: The expression evaluated to a falsy value: Mar 28 22:28:06 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:28:06 volumiorobi volumio[830]: error: updateQueue error: null Mar 28 22:28:07 volumiorobi sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 28 22:28:07 volumiorobi sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:07 volumiorobi systemd[1]: Started Volumio Bluetooth Module. Mar 28 22:28:07 volumiorobi sudo[1268]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:07 volumiorobi volumio[830]: info: Volumio BT Module successfully started Mar 28 22:28:07 volumiorobi sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 28 22:28:07 volumiorobi sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:07 volumiorobi volumiobt[1270]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 28 22:28:07 volumiorobi sudo[1271]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:07 volumiorobi volumio[830]: info: TidalConnect service stoped! Mar 28 22:28:07 volumiorobi volumio[830]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 22:28:07 volumiorobi volumio[830]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 22:28:07 volumiorobi sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 22:28:07 volumiorobi sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:07 volumiorobi systemd[1]: Started Volumio Tidal Connect Service. Mar 28 22:28:07 volumiorobi sudo[1283]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:08 volumiorobi volumio[830]: info: Executing endpoint tc_getconfig Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 28 22:28:08 volumiorobi vtcs[1285]: STARTING TidalConnect services, version: 1.3.0.19 Mar 28 22:28:08 volumiorobi vtcs[1285]: [2024-03-28 22:28:08.272] [tisoc] [critical] [DeviceInfo.cpp:56] ioctl error. ret=-1, errno="No such device" Mar 28 22:28:08 volumiorobi vtcs[1285]: [2024-03-28 22:28:08.272] [tisoc] [critical] [DeviceInfo.cpp:57] [ASSERT] generateDeviceId(0) Mar 28 22:28:08 volumiorobi vtcs[1285]: STARTED TidalConnect services. Mar 28 22:28:08 volumiorobi volumio[830]: info: Executing endpoint tc_connect Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 28 22:28:08 volumiorobi volumio[830]: info: Connecting to TidalConnect Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::servicePushState Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreStateMachine::pushState Mar 28 22:28:08 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::volumioPushState Mar 28 22:28:08 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output update for this device Mar 28 22:28:08 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output Mar 28 22:28:08 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:08 volumiorobi volumio[830]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::servicePushState Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreStateMachine::pushState Mar 28 22:28:08 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:08 volumiorobi volumio[830]: info: CoreCommandRouter::volumioPushState Mar 28 22:28:08 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output update for this device Mar 28 22:28:08 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output Mar 28 22:28:08 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:08 volumiorobi volumio[830]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Mar 28 22:28:08 volumiorobi sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 28 22:28:08 volumiorobi sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:28:08 volumiorobi volumiobt[1270]: pulseaudio: no process found Mar 28 22:28:08 volumiorobi sudo[1296]: pam_unix(sudo:session): session closed for user root Mar 28 22:28:08 volumiorobi dbus-daemon[501]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1307 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 28 22:28:08 volumiorobi systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 28 22:28:08 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:28:08 volumiorobi dbus-daemon[501]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 28 22:28:08 volumiorobi systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Successfully called chroot. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Successfully dropped privileges. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Successfully limited resources. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Running. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Canary thread running. Mar 28 22:28:08 volumiorobi rtkit-daemon[1308]: Watchdog thread running. Mar 28 22:28:08 volumiorobi dbus-daemon[501]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1308 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 28 22:28:08 volumiorobi systemd[1]: Starting Authorization Manager... Mar 28 22:28:08 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:28:08 volumiorobi polkitd[1311]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 28 22:28:08 volumiorobi dbus-daemon[501]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 28 22:28:08 volumiorobi systemd[1]: Started Authorization Manager. Mar 28 22:28:09 volumiorobi pulseaudio[1307]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 28 22:28:09 volumiorobi pulseaudio[1307]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 28 22:28:09 volumiorobi volumiobt[1270]: Applying permissions Mar 28 22:28:09 volumiorobi volumiobt[1270]: Setting BT discoverable and pairable Mar 28 22:28:09 volumiorobi bluetoothd[640]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Mar 28 22:28:09 volumiorobi bluetoothd[640]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Mar 28 22:28:09 volumiorobi pulseaudio[1307]: 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 28 22:28:09 volumiorobi volumio[830]: info: MRS: Getting audio outputs on start Mar 28 22:28:09 volumiorobi volumio[830]: info: MRS: Requesting all other devices output Mar 28 22:28:09 volumiorobi kernel: Bluetooth: RFCOMM TTY layer initialized Mar 28 22:28:09 volumiorobi kernel: Bluetooth: RFCOMM socket layer initialized Mar 28 22:28:09 volumiorobi kernel: Bluetooth: RFCOMM ver 1.11 Mar 28 22:28:09 volumiorobi volumiobt[1270]: [CHG] Controller B8:27:EB:DC:64:D3 Class: 0x0008041c Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [91B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [55B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [117B blob data] Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# -e power on Mar 28 22:28:09 volumiorobi volumiobt[1270]: Invalid command in menu main: -e Mar 28 22:28:09 volumiorobi volumiobt[1270]: Mar 28 22:28:09 volumiorobi volumiobt[1270]: Use "help" for a list of available commands in a menu. Mar 28 22:28:09 volumiorobi volumiobt[1270]: Use "menu " if you want to enter any submenu. Mar 28 22:28:09 volumiorobi volumiobt[1270]: Use "back" if you want to return to menu main. Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# agent on Mar 28 22:28:09 volumiorobi volumiobt[1270]: Agent is already registered Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# discoverable on Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# pairable on Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# agent NoInputNoOutput Mar 28 22:28:09 volumiorobi volumiobt[1270]: Agent is already registered Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# default-agent Mar 28 22:28:09 volumiorobi volumiobt[1270]: [bluetooth]# quit Mar 28 22:28:09 volumiorobi volumiobt[1270]: [59B blob data] Mar 28 22:28:10 volumiorobi volumio[830]: info: TidalConnect service started! Mar 28 22:28:10 volumiorobi volumio[830]: info: BOOT COMPLETED Mar 28 22:28:10 volumiorobi volumio[830]: [Metrics] CommandRouter: 65s 694.73ms Mar 28 22:28:10 volumiorobi volumio[830]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 22:28:10 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:10 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:10 volumiorobi volumio[830]: info: CoreCommandRouter::Close All Modals sent Mar 28 22:28:10 volumiorobi volumio[830]: info: CoreCommandRouter::Close All Modals sent Mar 28 22:28:10 volumiorobi volumio[830]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 28 22:28:10 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:28:10 volumiorobi volumio[830]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 28 22:28:10 volumiorobi volumio[830]: 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 Mar 28 22:28:10 volumiorobi volumio[830]: aplay: main:828: audio open error: No such device Mar 28 22:28:11 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 22:28:11 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 22:28:11 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 22:28:12 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 22:28:12 volumiorobi volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 28 22:28:12 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:28:12 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:14 volumiorobi volumio[830]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Mar 28 22:28:14 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:14 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 22:28:14 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:14 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 22:28:14 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 28 22:28:14 volumiorobi volumio-remote-updater[504]: No test mode Mar 28 22:28:14 volumiorobi volumio-remote-updater[504]: No alpha test mode Mar 28 22:28:15 volumiorobi volumio[830]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 28 22:28:15 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 22:28:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 22:28:30 volumiorobi volumio[983]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 28 22:28:31 volumiorobi volumio[983]: ..++++ Mar 28 22:28:47 volumiorobi volumio[983]: ..........................................................................................................................................++++ Mar 28 22:28:47 volumiorobi volumio[983]: e is 65537 (0x010001) Mar 28 22:28:47 volumiorobi volumio[983]: writing RSA key Mar 28 22:28:48 volumiorobi kernel: hwmon hwmon1: Undervoltage detected! Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: Retrieving Cloud Streaming UI Mar 28 22:28:50 volumiorobi volumio[830]: info: Getting Tidal Cloud Configuration Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: Getting Qobuz Cloud Configuration Mar 28 22:28:50 volumiorobi volumio[830]: info: Asking plugin for UI Config Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: Getting Spotify Cloud Configuration Mar 28 22:28:50 volumiorobi volumio[830]: info: Asking plugin for UI Config Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: Saving Spotify Acccount Mar 28 22:28:50 volumiorobi volumio[830]: info: Got it Mar 28 22:28:50 volumiorobi volumio[830]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 28 22:28:50 volumiorobi volumio[830]: info: Got Tidal Cloud Configuration Mar 28 22:28:50 volumiorobi volumio[830]: info: Got it Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 28 22:28:50 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 28 22:28:53 volumiorobi kernel: hwmon hwmon1: Voltage normalised Mar 28 22:28:53 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:28:53 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:28:54 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 22:28:54 volumiorobi volumio[830]: info: Getting Alsa Cards List without I2S DAC Mar 28 22:28:54 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 28 22:28:54 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:28:55 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 22:28:55 volumiorobi volumio[830]: info: Executing endpoint metavolumio Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 22:28:55 volumiorobi volumio[830]: info: Executing endpoint metavolumio Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 22:28:55 volumiorobi volumio[830]: info: Executing endpoint metavolumio Mar 28 22:28:55 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 28 22:29:16 volumiorobi volumio[830]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:16 volumiorobi volumio[830]: info: Enabling I2S DAC: HiFiBerry DAC+ Pro Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:16 volumiorobi sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 28 22:29:16 volumiorobi sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:16 volumiorobi sudo[1419]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:16 volumiorobi volumio[830]: info: No Overlays Loaded Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:16 volumiorobi sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Mar 28 22:29:16 volumiorobi sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:16 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:16 volumiorobi sudo[1422]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:16 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:16 volumiorobi volumio[830]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC+ Pro Mar 28 22:29:16 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 22:29:16 volumiorobi volumio[830]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC+ Pro Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 28 22:29:16 volumiorobi volumio[830]: info: Disabling external Volume Control Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 22:29:16 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 28 22:29:16 volumiorobi volumio[830]: info: Preparing to generate the ALSA configuration file Mar 28 22:29:16 volumiorobi volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 22:29:16 volumiorobi volumio[830]: info: Reading ALSA contributions from plugins. Mar 28 22:29:16 volumiorobi volumio[830]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay hifiberry-dacplus Mar 28 22:29:16 volumiorobi volumio[830]: * Failed to apply overlay '0_hifiberry-dacplus' (kernel) Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 22:29:17 volumiorobi volumio[830]: info: Getting Alsa Cards List without I2S DAC Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 22:29:17 volumiorobi volumio[830]: info: VolumeController:: Volume=100 Mute =false Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:29:17 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreStateMachine::pushState Mar 28 22:29:17 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::volumioPushState Mar 28 22:29:17 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output update for this device Mar 28 22:29:17 volumiorobi volumio[830]: info: MRS: Pushing multiroomSync output Mar 28 22:29:17 volumiorobi volumio[830]: info: Asound.conf file written Mar 28 22:29:17 volumiorobi sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 22:29:17 volumiorobi sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi sudo[1447]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: Output device has changed, restarting MPD Mar 28 22:29:17 volumiorobi volumio[830]: info: Output device has changed, restarting Shairport Sync Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:17 volumiorobi sudo[1452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 22:29:17 volumiorobi sudo[1452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi sudo[1452]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 22:29:17 volumiorobi sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi systemd[1]: Stopping Music Player Daemon... Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi vtcs[1285]: [2024-03-28 22:29:17.530] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 28 22:29:17 volumiorobi vtcs[1285]: [2024-03-28 22:29:17.532] [tisoc] [error] [SpkconServer.cpp:382] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 28 22:29:17 volumiorobi vtcs[1285]: [2024-03-28 22:29:17.532] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Mar 28 22:29:17 volumiorobi volumio[830]: info: Output device has changed, restarting MPD Mar 28 22:29:17 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:29:17 volumiorobi sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 22:29:17 volumiorobi sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 22:29:17 volumiorobi sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi volumio[830]: info: Output device has changed, restarting Shairport Sync Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:17 volumiorobi sudo[1465]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi systemd[1]: Stopping Volumio Tidal Connect Service... Mar 28 22:29:17 volumiorobi systemd[1]: mpd.service: Succeeded. Mar 28 22:29:17 volumiorobi systemd[1]: Stopped Music Player Daemon. Mar 28 22:29:17 volumiorobi systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 28 22:29:17 volumiorobi systemd[1]: vtcs.service: Succeeded. Mar 28 22:29:17 volumiorobi systemd[1]: Stopped Volumio Tidal Connect Service. Mar 28 22:29:17 volumiorobi sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 22:29:17 volumiorobi sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 22:29:17 volumiorobi volumio[830]: xcb_connection_has_error() returned true Mar 28 22:29:17 volumiorobi volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi systemd[1]: Starting Music Player Daemon... Mar 28 22:29:17 volumiorobi sudo[1463]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 28 22:29:17 volumiorobi systemd[1]: mpd.service: Succeeded. Mar 28 22:29:17 volumiorobi systemd[1]: Stopped Music Player Daemon. Mar 28 22:29:17 volumiorobi systemd[1]: Starting Music Player Daemon... Mar 28 22:29:17 volumiorobi kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Mar 28 22:29:17 volumiorobi sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 22:29:17 volumiorobi sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 22:29:17 volumiorobi sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:17 volumiorobi sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 22:29:17 volumiorobi sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:17 volumiorobi sudo[1479]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 28 22:29:17 volumiorobi sudo[1482]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:17 volumiorobi sudo[1485]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 28 22:29:17 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:17 volumiorobi volumio[830]: info: MPD Permissions set Mar 28 22:29:17 volumiorobi volumio[830]: info: MPD Permissions set Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::volumioGetState Mar 28 22:29:17 volumiorobi volumio[830]: info: CorePlayQueue::getTrack 0 Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:17 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:18 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 22:29:18 volumiorobi volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 22:29:18 volumiorobi volumio[830]: info: Starting Shairport Sync Mar 28 22:29:18 volumiorobi volumio[830]: info: Starting Shairport Sync Mar 28 22:29:18 volumiorobi sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 22:29:18 volumiorobi sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:18 volumiorobi sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 22:29:18 volumiorobi sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 22:29:18 volumiorobi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 22:29:18 volumiorobi systemd[1]: shairport-sync.service: Succeeded. Mar 28 22:29:18 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:18 volumiorobi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 22:29:18 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 28 22:29:18 volumiorobi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 22:29:18 volumiorobi sudo[1500]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:18 volumiorobi volumio[830]: info: Shairport-Sync Started Mar 28 22:29:18 volumiorobi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 28 22:29:18 volumiorobi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 28 22:29:18 volumiorobi systemd[1]: shairport-sync.service: Succeeded. Mar 28 22:29:18 volumiorobi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 28 22:29:18 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 28 22:29:18 volumiorobi volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 28 22:29:18 volumiorobi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 28 22:29:18 volumiorobi sudo[1502]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:18 volumiorobi volumio[830]: info: Shairport-Sync Started Mar 28 22:29:18 volumiorobi mpd[1480]: Mar 28 22:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 22:29:18 volumiorobi systemd[1]: Started Music Player Daemon. Mar 28 22:29:18 volumiorobi sudo[1469]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:18 volumiorobi sudo[1454]: pam_unix(sudo:session): session closed for user root Mar 28 22:29:18 volumiorobi volumio[830]: error: MPD error: The expression evaluated to a falsy value: Mar 28 22:29:18 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:29:18 volumiorobi volumio[830]: error: The expression evaluated to a falsy value: Mar 28 22:29:18 volumiorobi volumio[830]: assert.ok(self.idling) Mar 28 22:29:18 volumiorobi volumio[830]: error: updateQueue error: null Mar 28 22:29:19 volumiorobi volumio[830]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Mar 28 22:29:19 volumiorobi volumio[830]: info: PLUGIN onReboot : networkfs Mar 28 22:29:19 volumiorobi volumio[830]: info: PLUGIN onReboot : audiophonicsonoff Mar 28 22:29:19 volumiorobi volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 22:29:19 volumiorobi volumio[830]: TypeError: Cannot read property 'writeSync' of undefined Mar 28 22:29:19 volumiorobi volumio[830]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Mar 28 22:29:19 volumiorobi volumio[830]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Mar 28 22:29:19 volumiorobi volumio[830]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Mar 28 22:29:19 volumiorobi volumio[830]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 28 22:29:19 volumiorobi volumio[830]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 28 22:29:19 volumiorobi volumio[830]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Mar 28 22:29:19 volumiorobi volumio[830]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) Mar 28 22:29:19 volumiorobi volumio[830]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) Mar 28 22:29:19 volumiorobi volumio[830]: at Socket.emit (events.js:315:20) Mar 28 22:29:19 volumiorobi volumio[830]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 28 22:29:19 volumiorobi volumio[830]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 28 22:29:19 volumiorobi volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 22:29:20 volumiorobi sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-28 22:28 Mar 28 22:29:20 volumiorobi sudo[1521]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"