Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 17:22:00 volumio volumio[17455]: info: Received Get System Info Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:22:00 volumio volumio[17455]: info: Discovery: Getting this device information Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:00 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:22:00 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 11 17:22:02 volumio sudo[17824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 17:22:02 volumio sudo[17824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:02 volumio sudo[17826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 17:22:02 volumio sudo[17826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:02 volumio sudo[17828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 17:22:02 volumio sudo[17828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 17:22:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 17:22:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 17:22:02 volumio systemd[1]: mpd_monitor.service: Consumed 4.630s CPU time. Dec 11 17:22:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 17:22:02 volumio sudo[17824]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:02 volumio sudo[17826]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:02 volumio volumio[17455]: info: Successfully started MPD Monitor Dec 11 17:22:02 volumio mpd_monitor.sh[17832]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 17:22:02 volumio volumio[17455]: info: Successfully started MPD Monitor Dec 11 17:22:02 volumio sudo[17828]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:02 volumio volumio[17455]: info: Successfully started MPD Monitor Dec 11 17:22:03 volumio volumio[17455]: info: CoreCommandRouter::volumioPlay Dec 11 17:22:03 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:03 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:03 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:03 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:03 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:04 volumio volumio[17455]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin bluetooth to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin multiroom to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin metavolumio to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin cd_controller to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 11 17:22:08 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 11 17:22:09 volumio volumio[17455]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 11 17:22:09 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 11 17:22:09 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:09 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:09 volumio volumio[17455]: info: Starting MyVolumio Remote Streaming Endpoints Dec 11 17:22:09 volumio volumio[17455]: info: MyVolumio login type: Token Dec 11 17:22:09 volumio volumio[17455]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 11 17:22:09 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 11 17:22:11 volumio volumio[17455]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 11 17:22:11 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 11 17:22:11 volumio volumio[17455]: info: Streaming services startup Dec 11 17:22:11 volumio volumio[17455]: info: Starting Streaming Daemon Dec 11 17:22:11 volumio sudo[17851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 17:22:11 volumio sudo[17851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:11 volumio volumio[17455]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 11 17:22:11 volumio sudo[17851]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:11 volumio volumio[17455]: info: CoreCommandRouter::volumioPlay Dec 11 17:22:11 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:11 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:11 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:11 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:11 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:11 volumio volumio[17455]: error: Cannot start Volumio Streaming Daemon Dec 11 17:22:11 volumio volumio[17455]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 11 17:22:11 volumio volumio[17455]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 11 17:22:11 volumio volumio[17455]: error: MyVolumio Custom Token format not valid, refreshing it Dec 11 17:22:11 volumio volumio[17455]: info: MyVolumio login type: Token Dec 11 17:22:12 volumio volumio[17455]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 11 17:22:12 volumio volumio[17455]: info: MyVolumio token set successfully Dec 11 17:22:12 volumio volumio[17455]: info: MYVOLUMIO: Adding device Dec 11 17:22:12 volumio volumio[17455]: info: MYVOLUMIO: Evaluating Server Dec 11 17:22:13 volumio volumio[17455]: info: MyVolumio Plan changed: premium Dec 11 17:22:13 volumio volumio[17455]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 11 17:22:13 volumio volumio[17455]: info: Removing browser output: myVolumio user plan is not superstar Dec 11 17:22:13 volumio volumio[17455]: info: Removing audio output: Dec 11 17:22:13 volumio volumio[17455]: info: MYVOLUMIO: Adding device Dec 11 17:22:13 volumio volumio[17455]: info: MYVOLUMIO: Evaluating Server Dec 11 17:22:13 volumio volumio[17455]: info: Remote config written successfully Dec 11 17:22:13 volumio volumio[17455]: info: Starting Tunnel 1 Dec 11 17:22:13 volumio volumio[17455]: info: Starting Tunnel Connection Checker Dec 11 17:22:13 volumio volumio[17455]: info: Completed starting MyVolumio Plugin Dec 11 17:22:13 volumio volumio[17455]: info: MYVolumio Device enabled Dec 11 17:22:13 volumio volumio[17455]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 11 17:22:13 volumio volumio[17455]: info: MyVolumio status changed Dec 11 17:22:13 volumio volumio[17455]: info: Streaming services startup Dec 11 17:22:13 volumio volumio[17455]: info: Starting Streaming Daemon Dec 11 17:22:13 volumio volumio[17455]: info: Setting Geolocation for MyVolumio to eu4 Dec 11 17:22:13 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:13 volumio sudo[17896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 17:22:13 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:13 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:13 volumio sudo[17896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:13 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 11 17:22:13 volumio sudo[17896]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:13 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 11 17:22:13 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 11 17:22:13 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 11 17:22:14 volumio volumio[17455]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 11 17:22:14 volumio sudo[17901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 11 17:22:14 volumio sudo[17901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:14 volumio sudo[17901]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:14 volumio volumio[17455]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Dec 11 17:22:14 volumio volumio[17455]: info: MRS: MultiRoom plugin initialized Dec 11 17:22:14 volumio volumio[17455]: info: MRS: STOPPING SNAPCLIENT Dec 11 17:22:14 volumio volumio[17455]: info: MRS: Snap server stop Dec 11 17:22:15 volumio volumio[17455]: info: MRS: STOPPING volumioStreaming Dec 11 17:22:15 volumio sudo[17918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 11 17:22:15 volumio sudo[17918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio sudo[17920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 11 17:22:15 volumio sudo[17920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio sudo[17922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 11 17:22:15 volumio sudo[17922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio sudo[17925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 11 17:22:15 volumio sudo[17925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 11 17:22:15 volumio sudo[17925]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 11 17:22:15 volumio sudo[17920]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 11 17:22:15 volumio sudo[17918]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 11 17:22:15 volumio sudo[17922]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 11 17:22:15 volumio volumio[17455]: info: Preparing to generate the ALSA configuration file Dec 11 17:22:15 volumio volumio[17455]: info: Updating MyVolumio device info Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: Setting Geolocation for MyVolumio to eu6 Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: Successfully Added MyVolumio device Dec 11 17:22:15 volumio volumio[17455]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 17:22:15 volumio volumio[17455]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 11 17:22:15 volumio volumio[17455]: info: Reading ALSA contributions from plugins. Dec 11 17:22:15 volumio volumio[17455]: info: MRS: Removed streaming files Dec 11 17:22:15 volumio volumio[17455]: info: MRS: volumioStreaming STOPPED Dec 11 17:22:15 volumio volumio[17455]: info: MRS: SNAPSERVER STOPPED Dec 11 17:22:15 volumio volumio[17455]: info: MRS: SNAPCLIENT STOPPED Dec 11 17:22:15 volumio volumio[17455]: error: Cannot start Volumio Streaming Daemon Dec 11 17:22:15 volumio volumio[17455]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 11 17:22:15 volumio volumio[17455]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 17:22:15 volumio volumio[17455]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 11 17:22:15 volumio volumio[17455]: info: Received Get System Version Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 17:22:15 volumio volumio[17455]: info: Received Get System Info Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 17:22:15 volumio volumio[17455]: info: Discovery: Getting this device information Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:15 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 17:22:15 volumio volumio[17455]: info: Asound.conf file written Dec 11 17:22:15 volumio sudo[17932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 17:22:15 volumio sudo[17932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio sudo[17932]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: No state is present for card b1 Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 11 17:22:15 volumio volumio[17455]: No state is present for card b1 Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: No state is present for card Headphones Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 11 17:22:15 volumio volumio[17455]: No state is present for card Headphones Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: No state is present for card sndrpihifiberry Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: Found hardware: "HifiberryDacp" "" "" "" "" Dec 11 17:22:15 volumio volumio[17455]: Hardware is initialized using a generic method Dec 11 17:22:15 volumio volumio[17455]: No state is present for card sndrpihifiberry Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: No state is present for card Loopback Dec 11 17:22:15 volumio volumio[17455]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 11 17:22:15 volumio volumio[17455]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Dec 11 17:22:15 volumio volumio[17455]: Hardware is initialized using a generic method Dec 11 17:22:15 volumio volumio[17455]: No state is present for card Loopback Dec 11 17:22:15 volumio volumio[17455]: info: Output device has changed, restarting MPD Dec 11 17:22:15 volumio volumio[17455]: info: Output device has changed, restarting Shairport Sync Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:15 volumio sudo[17938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 17:22:15 volumio sudo[17938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio sudo[17940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 17:22:15 volumio sudo[17938]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:15 volumio sudo[17940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:15 volumio volumio[17455]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 17:22:15 volumio volumio[17455]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 11 17:22:15 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 11 17:22:15 volumio volumio[17455]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 11 17:22:15 volumio volumio[17455]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 11 17:22:15 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 11 17:22:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 17:22:15 volumio upmpdcli[1629]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 11 17:22:15 volumio volumio[17455]: info: MRS: Pushing multiroomSync output for this device Dec 11 17:22:15 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:15 volumio volumio[17455]: info: Adding audio output: Dec 11 17:22:15 volumio volumio[17455]: info: Adding audio output: Dec 11 17:22:15 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 17:22:15 volumio bluetoothd[910]: Path / reserved for Adv Monitor app :1.81 Dec 11 17:22:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 17:22:15 volumio systemd[1]: mpd.service: Consumed 1.495s CPU time. Dec 11 17:22:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 17:22:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 17:22:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 17:22:15 volumio volumio[17455]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 11 17:22:15 volumio volumio[17455]: info: Adding METAVOLUMIO REST API Endpoints Dec 11 17:22:15 volumio volumio[17455]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:22:15 volumio volumio[17455]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:22:15 volumio volumio[17455]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:22:15 volumio volumio[17455]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 11 17:22:15 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 11 17:22:15 volumio bluetoothd[910]: Adv Monitor app :1.81 disconnected from D-Bus Dec 11 17:22:15 volumio volumio[17455]: info: Preparing CD Folders Dec 11 17:22:15 volumio volumio[17455]: info: Adding CD REST API Endpoints Dec 11 17:22:15 volumio volumio[17455]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 11 17:22:15 volumio volumio[17455]: info: Starting UDEV Watcher for CD Dec 11 17:22:15 volumio volumio[17455]: info: Detecting CD presence with UDEV Dec 11 17:22:15 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 11 17:22:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 17:22:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 17:22:15 volumio sudo[17955]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 17:22:15 volumio sudo[17955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 17:22:15 volumio sudo[17955]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:17 volumio mpd[17960]: 2025-12-11T17:22:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 17:22:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 17:22:17 volumio sudo[17940]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:17 volumio upmpdcli[1629]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 11 17:22:20 volumio volumio[17455]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 11 17:22:20 volumio volumio[17455]: info: Adding inputs REST Endpoints Dec 11 17:22:20 volumio volumio[17455]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 11 17:22:20 volumio volumio[17455]: info: Scanning Audio Inputs Dec 11 17:22:20 volumio volumio[17455]: info: Checking against Known Cards name Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 17:22:20 volumio volumio[17455]: info: [1765470140762] CoreMusicLibrary::Adding element Loopback Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 17:22:20 volumio volumio[17455]: Cannot find translation for source Loopback Dec 11 17:22:20 volumio volumio[17455]: info: Checking against Known Cards name Dec 11 17:22:20 volumio volumio[17455]: info: Checking against Known Cards name Dec 11 17:22:20 volumio volumio[17455]: info: Adding Server instance for streaming Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 11 17:22:20 volumio volumio[17455]: error: Hi Res Audio Failed Login: Missing Login Data Dec 11 17:22:20 volumio volumio[17455]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 11 17:22:20 volumio volumio[17455]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 11 17:22:20 volumio volumio[17455]: info: Refreshing QOBUZ token Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 11 17:22:20 volumio volumio[17455]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 11 17:22:20 volumio volumio[17455]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 11 17:22:20 volumio volumio[17455]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 11 17:22:20 volumio sudo[17981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 17:22:20 volumio sudo[17981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:20 volumio volumio[17455]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 11 17:22:20 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 17:22:20 volumio volumio[17455]: info: Adding TIDAL REST API Endpoints Dec 11 17:22:20 volumio sudo[17988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 17:22:20 volumio systemd[1]: vtcs.service: Killing process 3152 (vtcs) with signal SIGKILL. Dec 11 17:22:20 volumio sudo[17988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:20 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 17:22:20 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 17:22:20 volumio systemd[1]: vtcs.service: Consumed 3.130s CPU time. Dec 11 17:22:20 volumio volumio[17455]: info: Updating MyVolumio device info Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:20 volumio volumio[17455]: info: MRS: Getting audio outputs on start Dec 11 17:22:20 volumio volumio[17455]: info: MRS: Requesting all other devices output Dec 11 17:22:20 volumio sudo[17981]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:20 volumio sudo[17988]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:20 volumio sudo[17992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 17:22:20 volumio sudo[17992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:20 volumio volumio[17455]: info: Successfully Updated MyVolumio device Dec 11 17:22:20 volumio volumio[17455]: info: Successfully Added MyVolumio device Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::volumioNext Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::next Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:20 volumio volumio[17455]: info: CoreCommandRouter::volumioNext Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::next Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:20 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:20 volumio volumio[17455]: info: CorePlayQueue::getTrack 2 Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrack 2 Dec 11 17:22:20 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 17:22:21 volumio volumio[17455]: info: [1765470141002] ControllerQobuz::clearAddPlayTrack Dec 11 17:22:21 volumio qobuz-connect[3093]: 20251211 17:22:21.005 [3093.3093] INFO SampleApp: Stopping Local configuration server Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrack 2 Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrack 2 Dec 11 17:22:21 volumio volumio[17455]: info: [1765470141008] ControllerQobuz::clearAddPlayTrack Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::updateTrackBlock Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrackBlock Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::updateTrackBlock Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrackBlock Dec 11 17:22:21 volumio volumio[17455]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 11 17:22:21 volumio volumio[17455]: info: MPD Permissions set Dec 11 17:22:21 volumio sudo[17995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 11 17:22:21 volumio sudo[17995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:21 volumio sudo[17995]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:21 volumio volumio[17455]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioStop Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:21 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:21 volumio volumio[17455]: info: Access Token successfully retrieved Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 17:22:21 volumio volumio[17455]: info: [1765470141376] CoreMusicLibrary::Adding element QOBUZ Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 17:22:21 volumio volumio[17455]: Cannot find translation for source Loopback Dec 11 17:22:21 volumio volumio[17455]: Cannot find translation for source QOBUZ Dec 11 17:22:21 volumio volumio[17455]: info: Stopping AccessToken refresher cron for QOBUZ Dec 11 17:22:21 volumio volumio[17455]: info: AccessToken refresher cron started for QOBUZ Dec 11 17:22:21 volumio volumio[17455]: info: Adding QOBUZ REST API Endpoints Dec 11 17:22:21 volumio volumio[17455]: error: updateQueue error: null Dec 11 17:22:21 volumio volumio[17455]: info: getStreamUrl took 563 milliseconds Dec 11 17:22:21 volumio volumio[17455]: error: Could not get QOBUZ Stream URL: [object Object] Dec 11 17:22:21 volumio volumio[17455]: info: getStreamUrl took 564 milliseconds Dec 11 17:22:21 volumio volumio[17455]: error: Could not get QOBUZ Stream URL: [object Object] Dec 11 17:22:21 volumio volumio[17455]: info: Successfully Updated MyVolumio device Dec 11 17:22:21 volumio volumio[17455]: info: Starting Shairport Sync Dec 11 17:22:21 volumio volumio[17455]: info: MRS: Found cast device: AFTKADE001-44[Cast] Dec 11 17:22:21 volumio volumio[17455]: info: Adding audio output: Dec 11 17:22:21 volumio volumio[17455]: info: MRS: Found cast device: Wohnzimmer[Cast] Dec 11 17:22:21 volumio volumio[17455]: info: Adding audio output: Dec 11 17:22:21 volumio sudo[18011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 11 17:22:21 volumio sudo[18011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:21 volumio sudo[18013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 17:22:21 volumio sudo[18013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioGetQueue Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::getQueue Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getQueue Dec 11 17:22:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 17:22:21 volumio autossh[1968]: received signal to exit (15) Dec 11 17:22:21 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Dec 11 17:22:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 17:22:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 17:22:21 volumio systemd[1]: shairport-sync.service: Consumed 1.709s CPU time. Dec 11 17:22:21 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Dec 11 17:22:21 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 17:22:21 volumio systemd[1]: sshtunnel.service: Consumed 1.458s CPU time. Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioSeek Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::seek Dec 11 17:22:21 volumio volumio[17455]: info: WARNING: No seek method for volatile plugin bluetooth Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:21 volumio volumio[17455]: info: CorePlayQueue::getTrack 2 Dec 11 17:22:21 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:21 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:21 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:21 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 17:22:21 volumio sudo[18013]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:21 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 11 17:22:21 volumio sudo[18011]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:21 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status Dec 11 17:22:21 volumio volumio[17455]: info: Shairport-Sync Started Dec 11 17:22:21 volumio volumio[17455]: info: Remote SSH Started Dec 11 17:22:21 volumio autossh[18018]: port set to 0, monitoring disabled Dec 11 17:22:21 volumio autossh[18018]: starting ssh (count 1) Dec 11 17:22:21 volumio autossh[18018]: ssh child pid is 18021 Dec 11 17:22:22 volumio volumiossh-tunnel[18021]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 11 17:22:22 volumio volumio[17455]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 11 17:22:22 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:22 volumio volumio[17455]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.IYuCIstyhFb51sysvqUeqDuIXUX2.2a1efc97acdb40a74ba7b2de145425ff.state.status' Dec 11 17:22:22 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 17:22:22 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 17:22:22 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 17:22:22 volumio sudo[17992]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:23 volumio volumio[17455]: info: Executing endpoint qc_getconfig Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.090 [18036.18036] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 17:22:23 volumio volumio[17455]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 17:22:23 volumio volumio[17455]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.095 [18036.18036] INFO VolumeManager: [0xf53258]: Setting new playback volume: 75 Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.095 [18036.18036] INFO VolumeManager: [0xf53258]: Setting new mute state: 0 Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.095 [18036.18036] INFO QobuzConnect: [0xf53c28]: Client initialized! Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.095 [18036.18036] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.111 [18036.18036] INFO LocalConfigManager: [0xf52b38]: Starting Local Configuration server Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.112 [18036.18036] INFO SampleApp: Starting Local configuration server Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.112 [18036.18036] INFO SampleApp: Connected to UNIX socket client 0xf3d818 Dec 11 17:22:23 volumio qobuz-connect[18036]: 20251211 17:22:23.240 [18036.18036] INFO SampleApp: Playback volume changed: 75 Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:23 volumio volumio[17455]: info: TidalConnect service stoped! Dec 11 17:22:23 volumio volumio[17455]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 17:22:23 volumio volumio[17455]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 17:22:23 volumio sudo[18054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 17:22:23 volumio sudo[18054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::volumioPlay Dec 11 17:22:23 volumio volumio[17455]: verbose: UNSET VOLATILE: Service: bluetooth Dec 11 17:22:23 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 11 17:22:23 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 11 17:22:23 volumio volumio[17455]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::play index 0 Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:23 volumio volumio[17455]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::resetVolumioState Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::getcurrentVolume Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:23 volumio volumio[17455]: info: CoreCommandRouter::volumioStop Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:23 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:23 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:23 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:23 volumio volumio[17455]: info: [1765470143994] ControllerQobuz::clearAddPlayTrack Dec 11 17:22:24 volumio volumio[17455]: info: VolumeController:: Volume=100 Mute =false Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: error: Help! Some callbacks for volumioPushState are crashing! Dec 11 17:22:24 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 17:22:24 volumio volumio[17455]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::updateTrackBlock Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrackBlock Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 17:22:24 volumio sudo[18054]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:24 volumio sudo[18063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 17:22:24 volumio sudo[18063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:24 volumio sudo[18065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:24 volumio sudo[18065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:24 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 17:22:24 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 17:22:24 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 17:22:24 volumio volumio[17455]: info: getStreamUrl took 210 milliseconds Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 17:22:24 volumio volumio[17455]: info: sendMpdCommand stop took 8 milliseconds Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 17:22:24 volumio volumio[17455]: info: Dec 11 17:22:24 volumio volumio[17455]: ---------------------------- MPD announces system playlist update Dec 11 17:22:24 volumio volumio[17455]: info: Ignoring MPD Status Update Dec 11 17:22:24 volumio volumio[17455]: info: sendMpdCommand clear took 9 milliseconds Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM" Dec 11 17:22:24 volumio volumio[17455]: info: VolumeController:: Volume=100 Mute =false Dec 11 17:22:24 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:24 volumio mpd_monitor.sh[18068]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:24 volumio sudo[18063]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: error: Help! Some callbacks for volumioPushState are crashing! Dec 11 17:22:24 volumio volumio[17455]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Dec 11 17:22:24 volumio sudo[18065]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: error: updateQueue error: null Dec 11 17:22:24 volumio volumio[17455]: info: ------------------------------ 95ms Dec 11 17:22:24 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:24 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:24 volumio volumio[17455]: info: Successfully started MPD Monitor Dec 11 17:22:24 volumio volumio[17455]: info: Executing endpoint tc_getconfig Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 17:22:24 volumio sudo[18074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:24 volumio vtcs[18059]: STARTING TidalConnect services, version: 1.5.2.56 Dec 11 17:22:24 volumio sudo[18074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:24 volumio vtcs[18059]: STARTED TidalConnect services. Dec 11 17:22:24 volumio volumio[17455]: info: Executing endpoint tc_connect Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 17:22:24 volumio volumio[17455]: info: Connecting to TidalConnect Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:24 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:24 volumio volumio[17455]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 11 17:22:24 volumio sudo[18074]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:24 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:24 volumio sudo[18091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:24 volumio sudo[18091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:24 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:24 volumio sudo[18093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:24 volumio sudo[18093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:24 volumio sudo[18091]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:24 volumio sudo[18093]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:24 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM" Dec 11 17:22:24 volumio volumio[17455]: info: Dec 11 17:22:24 volumio volumio[17455]: ---------------------------- MPD announces system playlist update Dec 11 17:22:24 volumio volumio[17455]: info: Ignoring MPD Status Update Dec 11 17:22:24 volumio volumio[17455]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM" took 4 milliseconds Dec 11 17:22:24 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand play Dec 11 17:22:24 volumio volumio[17455]: info: ------------------------------ 4ms Dec 11 17:22:24 volumio volumio[17455]: info: sendMpdCommand play took 3 milliseconds Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 17:22:24 volumio volumio[17455]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 11 17:22:24 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:24 volumio volumio[17455]: info: Dec 11 17:22:24 volumio volumio[17455]: ---------------------------- MPD announces state update: player Dec 11 17:22:24 volumio volumio[17455]: info: ControllerMpd::getState Dec 11 17:22:24 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 17:22:25 volumio volumio[17455]: info: Dec 11 17:22:25 volumio volumio[17455]: ---------------------------- MPD announces state update: player Dec 11 17:22:25 volumio volumio[17455]: info: sendMpdCommand status took 89 milliseconds Dec 11 17:22:25 volumio volumio[17455]: info: ControllerMpd::getState Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::parseState Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:22:25 volumio volumio[17455]: info: sendMpdCommand status took 4 milliseconds Dec 11 17:22:25 volumio volumio[17455]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::parseState Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:22:25 volumio volumio[17455]: info: ControllerMpd::pushState Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:25 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:25 volumio volumio[17455]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"245 Kbps","isStreaming":false,"title":"file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","trackType":"qobuz"} Dec 11 17:22:25 volumio volumio[17455]: verbose: CURRENT POSITION 0 Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::syncState stateService play Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::syncState currentStatus stop Dec 11 17:22:25 volumio volumio[17455]: info: ------------------------------ 107ms Dec 11 17:22:25 volumio volumio[17455]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 11 17:22:25 volumio volumio[17455]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:22:25 volumio volumio[17455]: info: ControllerMpd::pushState Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:25 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:25 volumio volumio[17455]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","trackType":"qobuz"} Dec 11 17:22:25 volumio volumio[17455]: verbose: CURRENT POSITION 0 Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::syncState stateService play Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::syncState currentStatus play Dec 11 17:22:25 volumio volumio[17455]: info: Received an update from plugin. extracting info from payload Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:25 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:25 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:25 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:25 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:25 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:25 volumio volumio[17455]: info: ------------------------------ 104ms Dec 11 17:22:25 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status play Dec 11 17:22:25 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status play Dec 11 17:22:25 volumio sudo[18109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 11 17:22:25 volumio sudo[18109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:25 volumio sudo[18112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 11 17:22:25 volumio sudo[18112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:25 volumio volumio-remote-updater[705]: Test mode disabled Dec 11 17:22:25 volumio volumio-remote-updater[705]: Alpha mode disabled Dec 11 17:22:25 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 17:22:25 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 11 17:22:25 volumio volumio[17455]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 11 17:22:25 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 11 17:22:25 volumio sudo[18109]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:25 volumio sudo[18112]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:25 volumio volumio[17455]: info: peppyspectrum Daemon Started Dec 11 17:22:25 volumio volumio[17455]: info: peppyspectrum Daemon Started Dec 11 17:22:25 volumio volumio[17455]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 11 17:22:26 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 11 17:22:27 volumio volumio[17455]: info: TidalConnect service started! Dec 11 17:22:27 volumio volumio[17455]: [Metrics] CommandRouter: 39s 701.58ms Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::Close All Modals sent Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::Close All Modals sent Dec 11 17:22:27 volumio volumio[17455]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 11 17:22:27 volumio volumio[17455]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Dec 11 17:22:27 volumio volumio[17455]: aplay: main:831: audio open error: Device or resource busy Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::volumioPause Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::pause Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::stPlaybackTimer Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::servicePause Dec 11 17:22:27 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::servicePause Dec 11 17:22:27 volumio volumio[17455]: info: [1765470147364] ControllerQobuz::pause Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 17:22:27 volumio volumio[17455]: info: ControllerMpd::pause Dec 11 17:22:27 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand pause Dec 11 17:22:27 volumio volumio[17455]: info: Dec 11 17:22:27 volumio volumio[17455]: ---------------------------- MPD announces state update: player Dec 11 17:22:27 volumio volumio[17455]: info: sendMpdCommand pause took 2 milliseconds Dec 11 17:22:27 volumio volumio[17455]: info: ControllerMpd::getState Dec 11 17:22:27 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:22:27 volumio volumio[17455]: info: sendMpdCommand status took 2 milliseconds Dec 11 17:22:27 volumio volumio[17455]: verbose: ControllerMpd::parseState Dec 11 17:22:27 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:22:27 volumio volumio[17455]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 11 17:22:27 volumio volumio[17455]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:22:27 volumio volumio[17455]: info: ControllerMpd::pushState Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:27 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:27 volumio volumio[17455]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3713,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"787 Kbps","isStreaming":false,"title":"file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3488627&eid=81778909&fmt=6&profile=raw&app_id=539451548&cid=3556785&etsp=1765473744&hmac=Js2wXWFj2V4-Q64fBf3l3ERY0jM","trackType":"qobuz"} Dec 11 17:22:27 volumio volumio[17455]: verbose: CURRENT POSITION 0 Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::syncState stateService pause Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::syncState currentStatus pause Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:27 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:27 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:27 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:27 volumio volumio[17455]: info: CoreStateMachine::stPlaybackTimer Dec 11 17:22:27 volumio volumio[17455]: info: ------------------------------ 55ms Dec 11 17:22:27 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status pause Dec 11 17:22:27 volumio sudo[18124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:27 volumio sudo[18124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:27 volumio volumio[17455]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 11 17:22:27 volumio sudo[18124]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:27 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioPlay Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::play index 1 Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::stop Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::updateTrackBlock Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrackBlock Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::stPlaybackTimer Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:28 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:28 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::serviceStop Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 0 Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::serviceStop Dec 11 17:22:28 volumio volumio[17455]: info: [1765470148595] ControllerQobuz::stop Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 17:22:28 volumio volumio[17455]: info: ControllerMpd::stop Dec 11 17:22:28 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 17:22:28 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:28 volumio volumio[17455]: info: Dec 11 17:22:28 volumio volumio[17455]: ---------------------------- MPD announces state update: player Dec 11 17:22:28 volumio volumio[17455]: info: sendMpdCommand stop took 59 milliseconds Dec 11 17:22:28 volumio volumio[17455]: info: ControllerMpd::getState Dec 11 17:22:28 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand status Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::play index undefined Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 1 Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::startPlaybackTimer Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 1 Dec 11 17:22:28 volumio volumio[17455]: info: [1765470148658] ControllerQobuz::clearAddPlayTrack Dec 11 17:22:28 volumio sudo[18129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:28 volumio volumio[17455]: info: sendMpdCommand status took 6 milliseconds Dec 11 17:22:28 volumio volumio[17455]: verbose: ControllerMpd::parseState Dec 11 17:22:28 volumio volumio[17455]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 17:22:28 volumio sudo[18129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:28 volumio volumio[17455]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 11 17:22:28 volumio volumio[17455]: verbose: ControllerMpd::parseTrackInfo Dec 11 17:22:28 volumio volumio[17455]: info: ControllerMpd::pushState Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::servicePushState Dec 11 17:22:28 volumio volumio[17455]: info: CoreStateMachine::pushState Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 1 Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioPushState Dec 11 17:22:28 volumio volumio[17455]: info: MRS: Pushing multiroomSync output update for this device Dec 11 17:22:28 volumio volumio[17455]: info: MRS: Pushing multiroomSync output Dec 11 17:22:28 volumio volumio[17455]: info: CoreCommandRouter::volumioGetState Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 1 Dec 11 17:22:28 volumio volumio[17455]: info: CorePlayQueue::getTrack 1 Dec 11 17:22:28 volumio volumio[17455]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Dec 11 17:22:28 volumio sudo[18129]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:28 volumio volumio[17455]: info: ------------------------------ 81ms Dec 11 17:22:28 volumio volumio[17455]: info: PeppySpectrum ---peppyspectrum status stop Dec 11 17:22:28 volumio sudo[18132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 11 17:22:28 volumio sudo[18132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 17:22:28 volumio volumio[17455]: info: getStreamUrl took 133 milliseconds Dec 11 17:22:28 volumio volumio[17455]: error: Could not get QOBUZ Stream URL: [object Object] Dec 11 17:22:28 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:28 volumio sudo[18132]: pam_unix(sudo:session): session closed for user root Dec 11 17:22:28 volumio volumio[17455]: info: peppyspectrum Daemon Stop Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 17:22:29 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 17:22:34 volumio volumio[17455]: info: BOOT COMPLETED Dec 11 17:22:40 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 17:22:40 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 17:22:50 volumio volumio[17455]: warn: [now-playing] MyBackgroundMonitor stopped Dec 11 17:22:50 volumio volumio[17455]: info: Disabling plugin now_playing Dec 11 17:22:50 volumio volumio[17455]: info: Done. Dec 11 17:22:58 volumio volumio[17455]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 17:23:06 volumio volumio[17455]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 11 17:23:06 volumio volumio[17455]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 11 17:23:08 volumio volumio[17455]: --2025-12-11 17:23:06-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/1280x400+30-custom.zip Dec 11 17:23:08 volumio volumio[17455]: Resolving github.com (github.com)... 140.82.121.4 Dec 11 17:23:08 volumio volumio[17455]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 11 17:23:08 volumio volumio[17455]: HTTP request sent, awaiting response... 503 Service Unavailable Dec 11 17:23:08 volumio volumio[17455]: 2025-12-11 17:23:08 ERROR 503: Service Unavailable. Dec 11 17:23:08 volumio volumio[17455]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 11 17:23:08 volumio volumio[17455]: /bin/rm: cannot remove '/tmp/1280x400+30-custom.zip*': No such file or directory Dec 11 17:23:08 volumio volumio[17455]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 17:23:08 volumio volumio[17455]: Error: Command failed: /bin/rm /tmp/1280x400+30-custom.zip* Dec 11 17:23:08 volumio volumio[17455]: /bin/rm: cannot remove '/tmp/1280x400+30-custom.zip*': No such file or directory Dec 11 17:23:08 volumio volumio[17455]: at checkExecSyncError (node:child_process:887:11) Dec 11 17:23:08 volumio volumio[17455]: at execSync (node:child_process:959:15) Dec 11 17:23:08 volumio volumio[17455]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 11 17:23:08 volumio volumio[17455]: at new Promise () Dec 11 17:23:08 volumio volumio[17455]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 11 17:23:08 volumio volumio[17455]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 11 17:23:08 volumio volumio[17455]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 11 17:23:08 volumio volumio[17455]: at Socket.emit (node:events:514:28) Dec 11 17:23:08 volumio volumio[17455]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 11 17:23:08 volumio volumio[17455]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 11 17:23:08 volumio volumio[17455]: status: 1, Dec 11 17:23:08 volumio volumio[17455]: signal: null, Dec 11 17:23:08 volumio volumio[17455]: output: [ Dec 11 17:23:08 volumio volumio[17455]: null, Dec 11 17:23:08 volumio volumio[17455]: , Dec 11 17:23:08 volumio volumio[17455]: Dec 11 17:23:08 volumio volumio[17455]: ], Dec 11 17:23:08 volumio volumio[17455]: pid: 18209, Dec 11 17:23:08 volumio volumio[17455]: stdout: , Dec 11 17:23:08 volumio volumio[17455]: stderr: Dec 11 17:23:08 volumio volumio[17455]: } Dec 11 17:23:08 volumio volumio[17455]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 17:23:09 volumio sudo[18225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 17:22' Dec 11 17:23:09 volumio sudo[18225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"