-- Logs begin at Tue 2024-09-17 17:06:54 CEST, end at Fri 2024-11-01 21:09:24 CET. -- Nov 01 21:08:05 volumio sudo[2297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 21:08:05 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:05 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:05 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 21:08:05 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:05 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:05 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 01 21:08:05 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:05 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:05 volumio volumio[1021]: info: Upmpdcli Daemon Started Nov 01 21:08:06 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 21:08:07 volumio volumio[1021]: info: CoreCommandRouter::volumioGetState Nov 01 21:08:09 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:08:09 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:08:09 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:08:09 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:08:09 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:08:09 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: Retrieving Cloud Streaming UI Nov 01 21:08:10 volumio volumio[1021]: info: Getting Tidal Cloud Configuration Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: Getting Qobuz Cloud Configuration Nov 01 21:08:10 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: Getting Spotify Cloud Configuration Nov 01 21:08:10 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: Saving Spotify Acccount Nov 01 21:08:10 volumio volumio[1021]: info: Got it Nov 01 21:08:10 volumio volumio[1021]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 21:08:10 volumio volumio[1021]: info: Got Tidal Cloud Configuration Nov 01 21:08:10 volumio volumio[1021]: info: Got it Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:10 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 21:08:10 volumio ntpd[776]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 21:08:14 volumio volumio[1021]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 01 21:08:14 volumio volumio[1021]: info: Received Get System Version Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 21:08:14 volumio volumio[1021]: info: Received Get System Info Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 21:08:14 volumio volumio[1021]: info: Discovery: Getting this device information Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::volumioGetState Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 21:08:14 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:18 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:20 volumio volumio[1021]: info: Disabling MyMusic plugin upnp Nov 01 21:08:20 volumio sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 01 21:08:20 volumio sudo[2307]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:20 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 01 21:08:20 volumio volumio[1021]: error: Upnp client error: Error: This socket has been ended by the other party Nov 01 21:08:20 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:21 volumio nmbd[747]: [2024/11/01 21:08:21.619825, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 21:08:21 volumio nmbd[747]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Nov 01 21:08:22 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:23 volumio volumio[1021]: info: Disabling MyMusic plugin airplay_emulation Nov 01 21:08:23 volumio volumio[1021]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Nov 01 21:08:23 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 21:08:23 volumio volumio[1021]: Cannot find translation for source QOBUZ Nov 01 21:08:23 volumio volumio[1021]: info: Disabling plugin airplay_emulation Nov 01 21:08:23 volumio volumio[1021]: info: Done. Nov 01 21:08:23 volumio sudo[2310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Nov 01 21:08:23 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 21:08:23 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 21:08:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 21:08:23 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:23 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:23 volumio volumio[1021]: info: Shairport-Sync Stopped Nov 01 21:08:26 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:30 volumio volumio[1021]: info: Disabling MyMusic plugin bluetooth Nov 01 21:08:30 volumio volumio[1021]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Nov 01 21:08:30 volumio sudo[2313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Nov 01 21:08:30 volumio sudo[2313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:30 volumio systemd[1]: Stopping Volumio Bluetooth Module... Nov 01 21:08:30 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:30 volumio volumiobt[2315]: Killing pulse Nov 01 21:08:30 volumio volumiobt[2315]: Killing BT Pair agent Nov 01 21:08:30 volumio bluetoothd[788]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSource Nov 01 21:08:30 volumio bluetoothd[788]: Endpoint unregistered: sender=:1.46 path=/MediaEndpoint/A2DPSink Nov 01 21:08:30 volumio volumiobt[1580]: Terminated Nov 01 21:08:30 volumio systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Nov 01 21:08:30 volumio systemd[1]: volumiobt.service: Succeeded. Nov 01 21:08:30 volumio systemd[1]: Stopped Volumio Bluetooth Module. Nov 01 21:08:30 volumio sudo[2313]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:30 volumio volumio[1021]: info: Volumio BT Module successfully stopped Nov 01 21:08:30 volumio volumio[1021]: info: Disabling plugin bluetooth Nov 01 21:08:30 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:31 volumio volumio[1021]: info: Disabling MyMusic plugin multiroom Nov 01 21:08:31 volumio volumio[1021]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Nov 01 21:08:31 volumio volumio[1021]: info: Disabling plugin multiroom Nov 01 21:08:31 volumio volumio[1021]: info: Preparing to generate the ALSA configuration file Nov 01 21:08:31 volumio volumio[1021]: info: Asound.conf file written Nov 01 21:08:31 volumio sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 21:08:31 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:31 volumio volumio[1021]: No state is present for card b1 Nov 01 21:08:31 volumio volumio[1021]: /usr/share/alsa/init/bcm2835:14: control element not found Nov 01 21:08:31 volumio volumio[1021]: No state is present for card b1 Nov 01 21:08:31 volumio volumio[1021]: No state is present for card Headphones Nov 01 21:08:31 volumio volumio[1021]: /usr/share/alsa/init/bcm2835:20: control element not found Nov 01 21:08:31 volumio volumio[1021]: No state is present for card Headphones Nov 01 21:08:31 volumio volumio[1021]: No state is present for card U20SU6 Nov 01 21:08:31 volumio volumio[1021]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3086" "" "" Nov 01 21:08:31 volumio volumio[1021]: Hardware is initialized using a generic method Nov 01 21:08:31 volumio volumio[1021]: No state is present for card U20SU6 Nov 01 21:08:31 volumio volumio[1021]: info: Output device has changed, restarting MPD Nov 01 21:08:31 volumio sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 21:08:31 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio volumio[1021]: info: Output device has changed, restarting Shairport Sync Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:08:31 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 21:08:31 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio systemd[1]: Stopping Music Player Daemon... Nov 01 21:08:31 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:31 volumio volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 21:08:31 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:31 volumio volumio[1021]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio vtcs[1595]: [2024-11-01 21:08:31.673] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 01 21:08:31 volumio vtcs[1595]: [2024-11-01 21:08:31.673] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 01 21:08:31 volumio vtcs[1595]: [2024-11-01 21:08:31.674] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe Nov 01 21:08:31 volumio systemd[1]: mpd.service: Succeeded. Nov 01 21:08:31 volumio systemd[1]: Stopped Music Player Daemon. Nov 01 21:08:31 volumio sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 21:08:31 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio systemd[1]: Starting Music Player Daemon... Nov 01 21:08:31 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:31 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 21:08:31 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:31 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 01 21:08:31 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 01 21:08:31 volumio systemd[1]: vtcs.service: Succeeded. Nov 01 21:08:31 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 01 21:08:31 volumio volumio[1021]: info: MPD Permissions set Nov 01 21:08:31 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 21:08:31 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:08:31 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:31 volumio sudo[2335]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 21:08:31 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio volumio[1021]: info: Starting Shairport Sync Nov 01 21:08:31 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 21:08:31 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 21:08:31 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:31 volumio volumio[1021]: info: Shairport-Sync Started Nov 01 21:08:32 volumio mpd[2349]: Nov 01 21:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 21:08:32 volumio systemd[1]: Started Music Player Daemon. Nov 01 21:08:32 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:32 volumio volumio[1021]: error: updateQueue error: null Nov 01 21:08:34 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:34 volumio volumio[1021]: info: TidalConnect service stoped! Nov 01 21:08:34 volumio volumio[1021]: info: TidalConnect service stoped! Nov 01 21:08:34 volumio volumio[1021]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 21:08:34 volumio volumio[1021]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 21:08:34 volumio sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 21:08:34 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:34 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 01 21:08:34 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:35 volumio volumio[1021]: info: Executing endpoint tc_getconfig Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 01 21:08:35 volumio vtcs[2369]: STARTING TidalConnect services, version: 1.4.0.34 Nov 01 21:08:35 volumio vtcs[2369]: STARTED TidalConnect services. Nov 01 21:08:35 volumio volumio[1021]: info: Executing endpoint tc_connect Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 01 21:08:35 volumio volumio[1021]: info: Connecting to TidalConnect Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::servicePushState Nov 01 21:08:35 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:35 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:35 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:35 volumio volumio[1021]: info: CorePlayQueue::getTrack 4 Nov 01 21:08:35 volumio volumio[1021]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::servicePushState Nov 01 21:08:35 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:35 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:35 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:35 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:35 volumio volumio[1021]: info: CorePlayQueue::getTrack 4 Nov 01 21:08:35 volumio volumio[1021]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 01 21:08:37 volumio volumio[1021]: info: TidalConnect service started! Nov 01 21:08:38 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:42 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:43 volumio volumio[1021]: info: CorePlayQueue::getTrack 4 Nov 01 21:08:43 volumio volumio[1021]: info: CorePlayQueue::getTrack 5 Nov 01 21:08:43 volumio volumio[1021]: info: Prefetching next song Nov 01 21:08:43 volumio volumio[1021]: info: [1730491723988] ControllerQobuz::prefetch Nov 01 21:08:43 volumio volumio[1021]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/6908578" Nov 01 21:08:43 volumio volumio[1021]: STREAMING PROXY: Handling url /?data=qobuz://song/6908578 Nov 01 21:08:43 volumio volumio[1021]: info: Executing endpoint getStreamUrlqobuz Nov 01 21:08:43 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 01 21:08:44 volumio volumio[1021]: info: getStreamUrl took 595 milliseconds Nov 01 21:08:44 volumio volumio[1021]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1847546&eid=6908578&fmt=6&profile=raw&app_id=539451548&cid=1244611&etsp=1730495324&hmac=8RQCCFIGZkoCkbMkGgjlaGOdVwE Nov 01 21:08:44 volumio volumio[1021]: STREAMING PROXY: Response: 200, length: 25020337 Nov 01 21:08:44 volumio volumio[1021]: STREAMING PROXY: Client dropped request, destroying Nov 01 21:08:44 volumio volumio[1021]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6908578" Nov 01 21:08:44 volumio volumio[1021]: info: Nov 01 21:08:44 volumio volumio[1021]: ---------------------------- MPD announces system playlist update Nov 01 21:08:44 volumio volumio[1021]: info: Ignoring MPD Status Update Nov 01 21:08:44 volumio volumio[1021]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/6908578" took 2 milliseconds Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 21:08:44 volumio volumio[1021]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 01 21:08:44 volumio volumio[1021]: info: Nov 01 21:08:44 volumio volumio[1021]: ---------------------------- MPD announces state update: options Nov 01 21:08:44 volumio volumio[1021]: info: ------------------------------ 3ms Nov 01 21:08:44 volumio volumio[1021]: info: sendMpdCommand consume 1 took 2 milliseconds Nov 01 21:08:44 volumio volumio[1021]: info: ControllerMpd::getState Nov 01 21:08:44 volumio volumio[1021]: verbose: ControllerMpd::sendMpdCommand status Nov 01 21:08:44 volumio volumio[1021]: info: sendMpdCommand status took 7 milliseconds Nov 01 21:08:44 volumio volumio[1021]: verbose: ControllerMpd::parseState Nov 01 21:08:44 volumio volumio[1021]: info: ControllerMpd::pushState Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::servicePushState Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:44 volumio volumio[1021]: info: CorePlayQueue::getTrack 4 Nov 01 21:08:44 volumio volumio[1021]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 21:08:44 volumio volumio[1021]: verbose: CURRENT POSITION 4 Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::syncState stateService stop Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::syncState currentStatus play Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:44 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:44 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:44 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:44 volumio volumio[1021]: info: ------------------------------ 42ms Nov 01 21:08:46 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:50 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:51 volumio volumio[1021]: info: Enabling MyMusic plugin multiroom Nov 01 21:08:51 volumio volumio[1021]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Nov 01 21:08:51 volumio volumio[1021]: info: Enabling plugin multiroom Nov 01 21:08:51 volumio volumio[1021]: info: Preparing to generate the ALSA configuration file Nov 01 21:08:51 volumio volumio[1021]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 01 21:08:51 volumio volumio[1021]: info: Reading ALSA contributions from plugins. Nov 01 21:08:51 volumio volumio[1021]: info: Asound.conf file written Nov 01 21:08:51 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 21:08:51 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:51 volumio volumio[1021]: No state is present for card b1 Nov 01 21:08:51 volumio volumio[1021]: /usr/share/alsa/init/bcm2835:14: control element not found Nov 01 21:08:51 volumio volumio[1021]: No state is present for card b1 Nov 01 21:08:51 volumio volumio[1021]: No state is present for card Headphones Nov 01 21:08:51 volumio volumio[1021]: /usr/share/alsa/init/bcm2835:20: control element not found Nov 01 21:08:51 volumio volumio[1021]: No state is present for card Headphones Nov 01 21:08:51 volumio volumio[1021]: No state is present for card U20SU6 Nov 01 21:08:51 volumio volumio[1021]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3086" "" "" Nov 01 21:08:51 volumio volumio[1021]: Hardware is initialized using a generic method Nov 01 21:08:51 volumio volumio[1021]: No state is present for card U20SU6 Nov 01 21:08:51 volumio volumio[1021]: info: Output device has changed, restarting MPD Nov 01 21:08:51 volumio sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 21:08:51 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio volumio[1021]: info: Output device has changed, restarting Shairport Sync Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:08:51 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio sudo[2437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 21:08:51 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio systemd[1]: Stopping Music Player Daemon... Nov 01 21:08:51 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:51 volumio volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 21:08:51 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:08:51 volumio volumio[1021]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio systemd[1]: mpd.service: Succeeded. Nov 01 21:08:51 volumio systemd[1]: Stopped Music Player Daemon. Nov 01 21:08:51 volumio vtcs[2369]: [2024-11-01 21:08:51.599] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 01 21:08:51 volumio vtcs[2369]: [2024-11-01 21:08:51.600] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 01 21:08:51 volumio volumio[1021]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 01 21:08:51 volumio volumio[1021]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 21:08:51 volumio systemd[1]: Starting Music Player Daemon... Nov 01 21:08:51 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 01 21:08:51 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 01 21:08:51 volumio systemd[1]: vtcs.service: Succeeded. Nov 01 21:08:51 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 01 21:08:51 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:51 volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 21:08:51 volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:51 volumio volumio[1021]: info: MPD Permissions set Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio sudo[2452]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio sudo[2443]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 21:08:51 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:08:51 volumio volumio[1021]: info: Done. Nov 01 21:08:51 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio volumio[1021]: info: Starting Shairport Sync Nov 01 21:08:51 volumio volumio[1021]: info: MRS: Found cast device: Lenovo-Smart-Clock-7e477798303ec38b91a60048b3370d8f Nov 01 21:08:51 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 21:08:51 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:08:51 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 21:08:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 21:08:51 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 21:08:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 21:08:51 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:51 volumio volumio[1021]: info: Shairport-Sync Started Nov 01 21:08:52 volumio mpd[2462]: Nov 01 21:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 21:08:52 volumio systemd[1]: Started Music Player Daemon. Nov 01 21:08:52 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:52 volumio volumio[1021]: error: updateQueue error: null Nov 01 21:08:54 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:54 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:54 volumio volumio[1021]: info: TidalConnect service stoped! Nov 01 21:08:54 volumio volumio[1021]: info: TidalConnect service stoped! Nov 01 21:08:54 volumio volumio[1021]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 21:08:54 volumio volumio[1021]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 21:08:54 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 21:08:54 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:08:54 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 01 21:08:54 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Nov 01 21:08:55 volumio volumio[1021]: info: Executing endpoint tc_getconfig Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 01 21:08:55 volumio vtcs[2483]: STARTING TidalConnect services, version: 1.4.0.34 Nov 01 21:08:55 volumio vtcs[2483]: STARTED TidalConnect services. Nov 01 21:08:55 volumio volumio[1021]: info: Executing endpoint tc_connect Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 01 21:08:55 volumio volumio[1021]: info: Connecting to TidalConnect Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::servicePushState Nov 01 21:08:55 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:55 volumio volumio[1021]: info: CorePlayQueue::getTrack 5 Nov 01 21:08:55 volumio volumio[1021]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::servicePushState Nov 01 21:08:55 volumio volumio[1021]: info: CoreStateMachine::pushState Nov 01 21:08:55 volumio volumio[1021]: info: CoreCommandRouter::volumioPushState Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output update for this device Nov 01 21:08:55 volumio volumio[1021]: info: MRS: Pushing multiroomSync output Nov 01 21:08:55 volumio volumio[1021]: info: CorePlayQueue::getTrack 5 Nov 01 21:08:55 volumio volumio[1021]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: Retrieving Cloud Streaming UI Nov 01 21:08:56 volumio volumio[1021]: info: Getting Tidal Cloud Configuration Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: Getting Qobuz Cloud Configuration Nov 01 21:08:56 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: Getting Spotify Cloud Configuration Nov 01 21:08:56 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: Saving Spotify Acccount Nov 01 21:08:56 volumio volumio[1021]: info: Got it Nov 01 21:08:56 volumio volumio[1021]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 21:08:56 volumio volumio[1021]: info: Got Tidal Cloud Configuration Nov 01 21:08:56 volumio volumio[1021]: info: Got it Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:08:56 volumio volumio[1021]: info: MRS: Getting audio outputs on start Nov 01 21:08:56 volumio volumio[1021]: info: MRS: Requesting all other devices output Nov 01 21:08:56 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 21:08:57 volumio volumio[1021]: info: TidalConnect service started! Nov 01 21:08:59 volumio volumio[1021]: info: CoreCommandRouter::volumioGetState Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: Retrieving Cloud Streaming UI Nov 01 21:09:00 volumio volumio[1021]: info: Getting Tidal Cloud Configuration Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: Getting Qobuz Cloud Configuration Nov 01 21:09:00 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: Getting Spotify Cloud Configuration Nov 01 21:09:00 volumio volumio[1021]: info: Asking plugin for UI Config Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: Saving Spotify Acccount Nov 01 21:09:00 volumio volumio[1021]: info: Got it Nov 01 21:09:00 volumio volumio[1021]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 21:09:00 volumio volumio[1021]: info: Got Tidal Cloud Configuration Nov 01 21:09:00 volumio volumio[1021]: info: Got it Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:09:00 volumio volumio[1021]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 21:09:01 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:01 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 21:09:01 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:09:01 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:09:01 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:09:01 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:09:01 volumio volumio[1021]: info: Executing endpoint metavolumio Nov 01 21:09:01 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 21:09:05 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:09 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:13 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:17 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:17 volumio volumio[1021]: info: Enabling MyMusic plugin airplay_emulation Nov 01 21:09:17 volumio volumio[1021]: info: Enabling plugin airplay_emulation Nov 01 21:09:17 volumio volumio[1021]: info: Loading plugin "airplay_emulation"... Nov 01 21:09:17 volumio volumio[1021]: info: Starting Shairport Sync Nov 01 21:09:17 volumio volumio[1021]: info: PLUGIN START: airplay_emulation Nov 01 21:09:17 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:09:17 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:09:17 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:09:17 volumio volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 21:09:17 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:09:17 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 21:09:17 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 21:09:17 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:09:17 volumio volumio[1021]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 21:09:17 volumio volumio[1021]: xcb_connection_has_error() returned true Nov 01 21:09:17 volumio volumio[1021]: info: Done. Nov 01 21:09:17 volumio volumio[1021]: info: Starting Shairport Sync Nov 01 21:09:17 volumio volumio[1021]: info: Starting Shairport Sync Nov 01 21:09:17 volumio sudo[2511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 21:09:17 volumio sudo[2511]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:09:17 volumio sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 21:09:17 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:09:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 21:09:17 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 21:09:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 21:09:17 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:09:17 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 21:09:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 21:09:17 volumio sudo[2511]: pam_unix(sudo:session): session closed for user root Nov 01 21:09:17 volumio volumio[1021]: info: Shairport-Sync Started Nov 01 21:09:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 21:09:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 01 21:09:17 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 21:09:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 21:09:17 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:09:17 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 21:09:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 21:09:17 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Nov 01 21:09:17 volumio volumio[1021]: info: Shairport-Sync Started Nov 01 21:09:21 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 21:09:21 volumio nmbd[747]: [2024/11/01 21:09:21.681981, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 21:09:21 volumio nmbd[747]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Nov 01 21:09:23 volumio volumio[1021]: info: Disabling MyMusic plugin upnp Nov 01 21:09:23 volumio sudo[2523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 01 21:09:23 volumio sudo[2523]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 21:09:23 volumio volumio[1021]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 21:09:24 volumio volumio[1021]: info: Enabling MyMusic plugin upnp Nov 01 21:09:24 volumio volumio[1021]: info: Enabling plugin upnp Nov 01 21:09:24 volumio volumio[1021]: info: Loading plugin "upnp"... Nov 01 21:09:24 volumio volumio[1021]: info: [1730491764648] Starting Upmpd Daemon Nov 01 21:09:24 volumio volumio[1021]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 21:09:24 volumio volumio[1021]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 21:09:24 volumio volumio[1021]: Error: listen EADDRINUSE: address already in use :::6599 Nov 01 21:09:24 volumio volumio[1021]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 01 21:09:24 volumio volumio[1021]: at listenInCluster (net.js:1366:12) Nov 01 21:09:24 volumio volumio[1021]: at Server.listen (net.js:1452:7) Nov 01 21:09:24 volumio volumio[1021]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 01 21:09:24 volumio volumio[1021]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 01 21:09:24 volumio volumio[1021]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 01 21:09:24 volumio volumio[1021]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 01 21:09:24 volumio volumio[1021]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 01 21:09:24 volumio volumio[1021]: code: 'EADDRINUSE', Nov 01 21:09:24 volumio volumio[1021]: errno: -98, Nov 01 21:09:24 volumio volumio[1021]: syscall: 'listen', Nov 01 21:09:24 volumio volumio[1021]: address: '::', Nov 01 21:09:24 volumio volumio[1021]: port: 6599 Nov 01 21:09:24 volumio volumio[1021]: } Nov 01 21:09:24 volumio volumio[1021]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 21:09:24 volumio sudo[2534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 21:08 Nov 01 21:09:24 volumio sudo[2534]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"