-- 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"