-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-04-24 14:57:54 CEST. -- Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: Retrieving Cloud Streaming UI Apr 24 14:56:02 volumio-koos volumio[9139]: info: Getting Tidal Cloud Configuration Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: Getting Qobuz Cloud Configuration Apr 24 14:56:02 volumio-koos volumio[9139]: info: Asking plugin for UI Config Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: Getting Spotify Cloud Configuration Apr 24 14:56:02 volumio-koos volumio[9139]: info: Asking plugin for UI Config Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: Saving Spotify Acccount Apr 24 14:56:02 volumio-koos volumio[9139]: info: Got it Apr 24 14:56:02 volumio-koos volumio[9139]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 24 14:56:02 volumio-koos volumio[9139]: info: Got Tidal Cloud Configuration Apr 24 14:56:02 volumio-koos volumio[9139]: info: Got it Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 24 14:56:06 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:08 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:56:08 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:56:08 volumio-koos volumio[9139]: info: Discovery: Getting this device information Apr 24 14:56:08 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetState Apr 24 14:56:08 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:56:09 volumio-koos volumio[9139]: info: Enabling MyMusic plugin bluetooth Apr 24 14:56:09 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Apr 24 14:56:09 volumio-koos volumio[9139]: info: Enabling plugin bluetooth Apr 24 14:56:09 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 24 14:56:09 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 14:56:09 volumio-koos volumio[9139]: info: Done. Apr 24 14:56:09 volumio-koos sudo[10956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio koos Apr 24 14:56:09 volumio-koos sudo[10956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:09 volumio-koos sudo[10956]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:09 volumio-koos volumio[9139]: info: Bluetooth name changed to Volumio koos Apr 24 14:56:10 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:12 volumio-koos sudo[10966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 24 14:56:12 volumio-koos sudo[10966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:12 volumio-koos systemd[1]: Started Volumio Bluetooth Module. Apr 24 14:56:12 volumio-koos sudo[10966]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:12 volumio-koos sudo[10973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 24 14:56:12 volumio-koos volumio[9139]: info: Volumio BT Module successfully started Apr 24 14:56:12 volumio-koos sudo[10973]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:12 volumio-koos volumiobt[10972]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 24 14:56:12 volumio-koos sudo[10973]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:13 volumio-koos sudo[10986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 24 14:56:13 volumio-koos sudo[10986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:13 volumio-koos volumiobt[10972]: pulseaudio: no process found Apr 24 14:56:13 volumio-koos sudo[10986]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:13 volumio-koos pulseaudio[10998]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 24 14:56:13 volumio-koos pulseaudio[10998]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 24 14:56:13 volumio-koos volumiobt[10972]: Applying permissions Apr 24 14:56:13 volumio-koos volumiobt[10972]: Setting BT discoverable and pairable Apr 24 14:56:13 volumio-koos bluetoothd[649]: Endpoint registered: sender=:1.98 path=/MediaEndpoint/A2DPSource Apr 24 14:56:13 volumio-koos bluetoothd[649]: Endpoint registered: sender=:1.98 path=/MediaEndpoint/A2DPSink Apr 24 14:56:13 volumio-koos pulseaudio[10998]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 24 14:56:13 volumio-koos volumiobt[10972]: [CHG] Controller C0:E4:34:69:A6:4E UUIDs: 00001112-0000-1000-8000-00805f9b34fb Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [117B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [55B blob data] Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# -e power on Apr 24 14:56:13 volumio-koos volumiobt[10972]: Invalid command in menu main: -e Apr 24 14:56:13 volumio-koos volumiobt[10972]: Apr 24 14:56:13 volumio-koos volumiobt[10972]: Use "help" for a list of available commands in a menu. Apr 24 14:56:13 volumio-koos volumiobt[10972]: Use "menu " if you want to enter any submenu. Apr 24 14:56:13 volumio-koos volumiobt[10972]: Use "back" if you want to return to menu main. Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# agent on Apr 24 14:56:13 volumio-koos volumiobt[10972]: Agent is already registered Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# discoverable on Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# pairable on Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# agent NoInputNoOutput Apr 24 14:56:13 volumio-koos volumiobt[10972]: Agent is already registered Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# default-agent Apr 24 14:56:13 volumio-koos volumiobt[10972]: [bluetooth]# quit Apr 24 14:56:13 volumio-koos volumiobt[10972]: [59B blob data] Apr 24 14:56:14 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:16 volumio-koos volumio[9139]: info: Disabling MyMusic plugin cd_controller Apr 24 14:56:16 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller Apr 24 14:56:16 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Apr 24 14:56:16 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:56:16 volumio-koos volumio[9139]: info: Stopping UDEV Watcher for CD Apr 24 14:56:16 volumio-koos volumio[9139]: info: Disabling plugin cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:18 volumio-koos volumio[9139]: info: Enabling MyMusic plugin cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: Enabling plugin cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: Preparing CD Folders Apr 24 14:56:18 volumio-koos volumio[9139]: info: Adding CD REST API Endpoints Apr 24 14:56:18 volumio-koos volumio[9139]: info: Updating cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 24 14:56:18 volumio-koos volumio[9139]: info: Starting UDEV Watcher for CD Apr 24 14:56:18 volumio-koos volumio[9139]: info: Detecting CD presence with UDEV Apr 24 14:56:18 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 24 14:56:18 volumio-koos volumio[9139]: info: Done. Apr 24 14:56:22 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:25 volumio-koos volumio[9139]: info: Apr 24 14:56:25 volumio-koos volumio[9139]: ---------------------------- MPD announces system playlist update Apr 24 14:56:25 volumio-koos volumio[9139]: info: Ignoring MPD Status Update Apr 24 14:56:25 volumio-koos volumio[9139]: info: Apr 24 14:56:25 volumio-koos volumio[9139]: ---------------------------- MPD announces state update: player Apr 24 14:56:25 volumio-koos volumio[9139]: info: ControllerMpd::getState Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand status Apr 24 14:56:25 volumio-koos volumio[9139]: info: ------------------------------ 3ms Apr 24 14:56:25 volumio-koos volumio[9139]: info: sendMpdCommand status took 1 milliseconds Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: ControllerMpd::parseState Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 14:56:25 volumio-koos volumio[9139]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: ControllerMpd::parseTrackInfo Apr 24 14:56:25 volumio-koos volumio[9139]: info: ControllerMpd::pushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":317110,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO Radio 2 - De Koninklijke 500 - NPO","artist":"NPO Radio2","album":null,"uri":"http://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Apr 24 14:56:25 volumio-koos volumio[9139]: verbose: CURRENT POSITION 0 Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreStateMachine::syncState stateService play Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreStateMachine::syncState currentStatus play Apr 24 14:56:25 volumio-koos volumio[9139]: info: Received an update from plugin. extracting info from payload Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:25 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:25 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:25 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:25 volumio-koos volumio[9139]: info: ------------------------------ 56ms Apr 24 14:56:25 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:25 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:26 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:30 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:34 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:38 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:56:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:56:39 volumio-koos volumio[9139]: info: Discovery: Getting this device information Apr 24 14:56:39 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetState Apr 24 14:56:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:56:42 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:43 volumio-koos volumio[9139]: info: Disabling MyMusic plugin multiroom Apr 24 14:56:43 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Apr 24 14:56:43 volumio-koos volumio[9139]: info: Disabling plugin multiroom Apr 24 14:56:43 volumio-koos volumio[9139]: info: Preparing to generate the ALSA configuration file Apr 24 14:56:43 volumio-koos volumio[9139]: info: Asound.conf file written Apr 24 14:56:43 volumio-koos sudo[11053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 14:56:43 volumio-koos sudo[11053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos sudo[11053]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:43 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:43 volumio-koos volumio[9139]: info: Output device has changed, restarting MPD Apr 24 14:56:43 volumio-koos volumio[9139]: info: Output device has changed, restarting Shairport Sync Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:43 volumio-koos sudo[11065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 14:56:43 volumio-koos sudo[11068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 14:56:43 volumio-koos sudo[11065]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos sudo[11068]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos sudo[11065]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:43 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:43 volumio-koos volumio[9139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos systemd[1]: Stopping Music Player Daemon... Apr 24 14:56:43 volumio-koos vtcs[9695]: [2025-04-24 14:56:43.321] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 24 14:56:43 volumio-koos vtcs[9695]: [2025-04-24 14:56:43.322] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 24 14:56:43 volumio-koos vtcs[9695]: [2025-04-24 14:56:43.322] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Apr 24 14:56:43 volumio-koos sudo[11087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:43 volumio-koos sudo[11087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:43 volumio-koos volumio[9139]: info: MPD Permissions set Apr 24 14:56:43 volumio-koos systemd[1]: mpd.service: Succeeded. Apr 24 14:56:43 volumio-koos systemd[1]: Stopped Music Player Daemon. Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos systemd[1]: Starting Music Player Daemon... Apr 24 14:56:43 volumio-koos systemd[1]: Stopping Volumio Tidal Connect Service... Apr 24 14:56:43 volumio-koos systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 24 14:56:43 volumio-koos systemd[1]: vtcs.service: Succeeded. Apr 24 14:56:43 volumio-koos systemd[1]: Stopped Volumio Tidal Connect Service. Apr 24 14:56:43 volumio-koos sudo[11093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:43 volumio-koos sudo[11087]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos sudo[11093]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 14:56:43 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:43 volumio-koos sudo[11093]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:43 volumio-koos sudo[11096]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 14:56:43 volumio-koos volumio[9139]: info: Starting Shairport Sync Apr 24 14:56:43 volumio-koos sudo[11096]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos sudo[11096]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos sudo[11121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:56:43 volumio-koos sudo[11121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:43 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:56:43 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:56:43 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:43 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:43 volumio-koos sudo[11121]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:43 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:43 volumio-koos volumio[9139]: info: Shairport-Sync Started Apr 24 14:56:44 volumio-koos mpd[11138]: Apr 24 14:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 14:56:44 volumio-koos systemd[1]: Started Music Player Daemon. Apr 24 14:56:44 volumio-koos sudo[11068]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:44 volumio-koos volumio[9139]: error: updateQueue error: null Apr 24 14:56:46 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:56:46 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:56:46 volumio-koos volumio[9139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:56:46 volumio-koos volumio[9139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:56:46 volumio-koos sudo[11154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 24 14:56:46 volumio-koos sudo[11154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:46 volumio-koos systemd[1]: Started Volumio Tidal Connect Service. Apr 24 14:56:46 volumio-koos sudo[11154]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:46 volumio-koos volumio[9139]: info: Executing endpoint tc_getconfig Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 24 14:56:46 volumio-koos vtcs[11160]: STARTING TidalConnect services, version: 1.5.0.45 Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:46 volumio-koos vtcs[11160]: STARTED TidalConnect services. Apr 24 14:56:46 volumio-koos volumio[9139]: info: Executing endpoint tc_connect Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 24 14:56:46 volumio-koos volumio[9139]: info: Connecting to TidalConnect Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:46 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:46 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:56:46 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:46 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:46 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:46 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:56:46 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:56:46 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:46 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:48 volumio-koos volumio[9139]: info: Enabling MyMusic plugin multiroom Apr 24 14:56:48 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Apr 24 14:56:48 volumio-koos volumio[9139]: info: Enabling plugin multiroom Apr 24 14:56:48 volumio-koos volumio[9139]: info: Preparing to generate the ALSA configuration file Apr 24 14:56:48 volumio-koos volumio[9139]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 24 14:56:48 volumio-koos volumio[9139]: info: Reading ALSA contributions from plugins. Apr 24 14:56:48 volumio-koos volumio[9139]: info: Asound.conf file written Apr 24 14:56:48 volumio-koos sudo[11183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 14:56:48 volumio-koos sudo[11183]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos sudo[11183]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:48 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:48 volumio-koos volumio[9139]: info: Output device has changed, restarting MPD Apr 24 14:56:48 volumio-koos volumio[9139]: info: Output device has changed, restarting Shairport Sync Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:48 volumio-koos sudo[11195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 14:56:48 volumio-koos sudo[11195]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos sudo[11198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 14:56:48 volumio-koos sudo[11195]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos sudo[11198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:48 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:48 volumio-koos volumio[9139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos systemd[1]: Stopping Music Player Daemon... Apr 24 14:56:48 volumio-koos vtcs[11160]: [2025-04-24 14:56:48.513] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 24 14:56:48 volumio-koos vtcs[11160]: [2025-04-24 14:56:48.513] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 24 14:56:48 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 24 14:56:48 volumio-koos volumio[9139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos sudo[11217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:48 volumio-koos systemd[1]: mpd.service: Succeeded. Apr 24 14:56:48 volumio-koos systemd[1]: Stopped Music Player Daemon. Apr 24 14:56:48 volumio-koos sudo[11217]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos systemd[1]: Starting Music Player Daemon... Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:48 volumio-koos systemd[1]: Stopping Volumio Tidal Connect Service... Apr 24 14:56:48 volumio-koos systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 24 14:56:48 volumio-koos systemd[1]: vtcs.service: Succeeded. Apr 24 14:56:48 volumio-koos systemd[1]: Stopped Volumio Tidal Connect Service. Apr 24 14:56:48 volumio-koos volumio[9139]: info: MPD Permissions set Apr 24 14:56:48 volumio-koos sudo[11228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:48 volumio-koos sudo[11217]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos sudo[11228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 14:56:48 volumio-koos sudo[11224]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 14:56:48 volumio-koos sudo[11224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:48 volumio-koos sudo[11228]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos volumio[9139]: info: Done. Apr 24 14:56:48 volumio-koos sudo[11224]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos volumio[9139]: info: Starting Shairport Sync Apr 24 14:56:48 volumio-koos sudo[11258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:56:48 volumio-koos sudo[11258]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:48 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:48 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:56:48 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:48 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:48 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:48 volumio-koos sudo[11258]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:48 volumio-koos volumio[9139]: info: Shairport-Sync Started Apr 24 14:56:49 volumio-koos mpd[11264]: Apr 24 14:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 14:56:49 volumio-koos systemd[1]: Started Music Player Daemon. Apr 24 14:56:49 volumio-koos sudo[11198]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:49 volumio-koos volumio[9139]: error: updateQueue error: null Apr 24 14:56:49 volumio-koos volumio[9139]: info: TidalConnect service started! Apr 24 14:56:50 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:51 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:56:51 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:56:51 volumio-koos volumio[9139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:56:51 volumio-koos volumio[9139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:56:51 volumio-koos sudo[11299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 24 14:56:51 volumio-koos sudo[11299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:51 volumio-koos systemd[1]: Started Volumio Tidal Connect Service. Apr 24 14:56:51 volumio-koos sudo[11299]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:51 volumio-koos volumio[9139]: info: Executing endpoint tc_getconfig Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 24 14:56:51 volumio-koos vtcs[11305]: STARTING TidalConnect services, version: 1.5.0.45 Apr 24 14:56:51 volumio-koos vtcs[11305]: STARTED TidalConnect services. Apr 24 14:56:51 volumio-koos volumio[9139]: info: Executing endpoint tc_connect Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 24 14:56:51 volumio-koos volumio[9139]: info: Connecting to TidalConnect Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:51 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:56:51 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:51 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:52 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:56:52 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:56:52 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:56:52 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:56:52 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:52 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:56:53 volumio-koos volumio[9139]: info: MRS: Getting audio outputs on start Apr 24 14:56:53 volumio-koos volumio[9139]: info: MRS: Requesting all other devices output Apr 24 14:56:54 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:56:54 volumio-koos volumio[9139]: info: TidalConnect service started! Apr 24 14:56:57 volumio-koos volumio[9139]: info: Disabling MyMusic plugin multiroom Apr 24 14:56:57 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Apr 24 14:56:57 volumio-koos volumio[9139]: info: Disabling plugin multiroom Apr 24 14:56:57 volumio-koos volumio[9139]: info: Preparing to generate the ALSA configuration file Apr 24 14:56:57 volumio-koos volumio[9139]: info: Asound.conf file written Apr 24 14:56:57 volumio-koos sudo[11329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 14:56:57 volumio-koos sudo[11329]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos sudo[11329]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:57 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:57 volumio-koos volumio[9139]: info: Output device has changed, restarting MPD Apr 24 14:56:57 volumio-koos volumio[9139]: info: Output device has changed, restarting Shairport Sync Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:57 volumio-koos sudo[11341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 14:56:57 volumio-koos sudo[11344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 14:56:57 volumio-koos sudo[11341]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos sudo[11344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos sudo[11341]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos volumio[9139]: No protocol specified Apr 24 14:56:57 volumio-koos volumio[9139]: xcb_connection_has_error() returned true Apr 24 14:56:57 volumio-koos volumio[9139]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos systemd[1]: Stopping Music Player Daemon... Apr 24 14:56:57 volumio-koos vtcs[11305]: [2025-04-24 14:56:57.186] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 24 14:56:57 volumio-koos vtcs[11305]: [2025-04-24 14:56:57.187] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 24 14:56:57 volumio-koos sudo[11363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:57 volumio-koos sudo[11363]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos systemd[1]: mpd.service: Succeeded. Apr 24 14:56:57 volumio-koos systemd[1]: Stopped Music Player Daemon. Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:57 volumio-koos systemd[1]: Starting Music Player Daemon... Apr 24 14:56:57 volumio-koos volumio[9139]: info: MPD Permissions set Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos sudo[11369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 14:56:57 volumio-koos systemd[1]: Stopping Volumio Tidal Connect Service... Apr 24 14:56:57 volumio-koos systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 24 14:56:57 volumio-koos systemd[1]: vtcs.service: Succeeded. Apr 24 14:56:57 volumio-koos systemd[1]: Stopped Volumio Tidal Connect Service. Apr 24 14:56:57 volumio-koos sudo[11363]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos sudo[11369]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 14:56:57 volumio-koos sudo[11369]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:57 volumio-koos volumio[9139]: info: Starting Shairport Sync Apr 24 14:56:57 volumio-koos sudo[11371]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 14:56:57 volumio-koos sudo[11371]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos sudo[11371]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos sudo[11396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:56:57 volumio-koos sudo[11396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:56:57 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:56:57 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:56:57 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:56:57 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 24 14:56:57 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:56:57 volumio-koos sudo[11396]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:57 volumio-koos volumio[9139]: info: Shairport-Sync Started Apr 24 14:56:58 volumio-koos mpd[11408]: Apr 24 14:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 14:56:58 volumio-koos systemd[1]: Started Music Player Daemon. Apr 24 14:56:58 volumio-koos sudo[11344]: pam_unix(sudo:session): session closed for user root Apr 24 14:56:58 volumio-koos volumio[9139]: error: updateQueue error: null Apr 24 14:56:58 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:00 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:57:00 volumio-koos volumio[9139]: info: TidalConnect service stoped! Apr 24 14:57:00 volumio-koos volumio[9139]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:57:00 volumio-koos volumio[9139]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 24 14:57:00 volumio-koos sudo[11490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 24 14:57:00 volumio-koos sudo[11490]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:00 volumio-koos systemd[1]: Started Volumio Tidal Connect Service. Apr 24 14:57:00 volumio-koos sudo[11490]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:00 volumio-koos volumio[9139]: info: Executing endpoint tc_getconfig Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 24 14:57:00 volumio-koos vtcs[11496]: STARTING TidalConnect services, version: 1.5.0.45 Apr 24 14:57:00 volumio-koos vtcs[11496]: STARTED TidalConnect services. Apr 24 14:57:00 volumio-koos volumio[9139]: info: Executing endpoint tc_connect Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 24 14:57:00 volumio-koos volumio[9139]: info: Connecting to TidalConnect Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:00 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:00 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:00 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:00 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:00 volumio-koos volumio[9139]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 24 14:57:00 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:00 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioStop Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::stop Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::stPlaybackTimer Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::updateTrackBlock Apr 24 14:57:01 volumio-koos volumio[9139]: info: CorePlayQueue::getTrackBlock Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:01 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:01 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:01 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:01 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:01 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreStateMachine::serviceStop Apr 24 14:57:01 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:01 volumio-koos volumio[9139]: info: CoreCommandRouter::serviceStop Apr 24 14:57:01 volumio-koos volumio[9139]: info: [1745499421332] ControllerWebradio::stop Apr 24 14:57:01 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 14:57:01 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:01 volumio-koos volumio[9139]: info: sendMpdCommand stop took 35 milliseconds Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPlay Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreStateMachine::play index undefined Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 14:57:02 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreStateMachine::startPlaybackTimer Apr 24 14:57:02 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 14:57:02 volumio-koos volumio[9139]: info: [1745499422424] ControllerWebradio::clearAddPlayTrack Apr 24 14:57:02 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 14:57:02 volumio-koos volumio[9139]: info: sendMpdCommand stop took 0 milliseconds Apr 24 14:57:02 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 14:57:02 volumio-koos volumio[9139]: info: Apr 24 14:57:02 volumio-koos volumio[9139]: ---------------------------- MPD announces system playlist update Apr 24 14:57:02 volumio-koos volumio[9139]: info: Ignoring MPD Status Update Apr 24 14:57:02 volumio-koos volumio[9139]: info: sendMpdCommand clear took 1 milliseconds Apr 24 14:57:02 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.omroep.nl/radio2-bb-mp3" Apr 24 14:57:02 volumio-koos volumio[9139]: error: updateQueue error: null Apr 24 14:57:02 volumio-koos volumio[9139]: info: ------------------------------ 2ms Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:02 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.omroep.nl/radio2-bb-mp3" Apr 24 14:57:02 volumio-koos volumio[9139]: info: Apr 24 14:57:02 volumio-koos volumio[9139]: ---------------------------- MPD announces system playlist update Apr 24 14:57:02 volumio-koos volumio[9139]: info: Ignoring MPD Status Update Apr 24 14:57:02 volumio-koos volumio[9139]: info: sendMpdCommand add "http://icecast.omroep.nl/radio2-bb-mp3" took 2 milliseconds Apr 24 14:57:02 volumio-koos volumio[9139]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 14:57:02 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand play Apr 24 14:57:02 volumio-koos volumio[9139]: info: ------------------------------ 3ms Apr 24 14:57:02 volumio-koos volumio[9139]: info: sendMpdCommand play took 2 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: info: Apr 24 14:57:03 volumio-koos volumio[9139]: ---------------------------- MPD announces state update: player Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::getState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand status Apr 24 14:57:03 volumio-koos volumio[9139]: info: Apr 24 14:57:03 volumio-koos volumio[9139]: ---------------------------- MPD announces state update: player Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand status took 2 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::getState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand status Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand status took 1 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseTrackInfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio2-bb-mp3","artist":null,"album":null,"uri":"http://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: CURRENT POSITION 0 Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState stateService play Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState currentStatus stop Apr 24 14:57:03 volumio-koos volumio[9139]: info: ------------------------------ 8ms Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseTrackInfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio2-bb-mp3","artist":null,"album":null,"uri":"http://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: CURRENT POSITION 0 Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState stateService play Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState currentStatus play Apr 24 14:57:03 volumio-koos volumio[9139]: info: Received an update from plugin. extracting info from payload Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: ------------------------------ 82ms Apr 24 14:57:03 volumio-koos volumio[9139]: info: Apr 24 14:57:03 volumio-koos volumio[9139]: ---------------------------- MPD announces system playlist update Apr 24 14:57:03 volumio-koos volumio[9139]: info: Ignoring MPD Status Update Apr 24 14:57:03 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:03 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:03 volumio-koos volumio[9139]: info: Apr 24 14:57:03 volumio-koos volumio[9139]: ---------------------------- MPD announces system playlist update Apr 24 14:57:03 volumio-koos volumio[9139]: info: Ignoring MPD Status Update Apr 24 14:57:03 volumio-koos volumio[9139]: info: Apr 24 14:57:03 volumio-koos volumio[9139]: ---------------------------- MPD announces state update: player Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::getState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand status Apr 24 14:57:03 volumio-koos volumio[9139]: info: ------------------------------ 16ms Apr 24 14:57:03 volumio-koos volumio[9139]: info: ------------------------------ 3ms Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand status took 3 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseState Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: ControllerMpd::parseTrackInfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: ControllerMpd::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::servicePushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO Radio 2 - De Koninklijke 500 - NPO","artist":"NPO Radio2","album":null,"uri":"http://icecast.omroep.nl/radio2-bb-mp3","trackType":""} Apr 24 14:57:03 volumio-koos volumio[9139]: verbose: CURRENT POSITION 0 Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState stateService play Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::syncState currentStatus play Apr 24 14:57:03 volumio-koos volumio[9139]: info: Received an update from plugin. extracting info from payload Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreStateMachine::pushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output update for this device Apr 24 14:57:03 volumio-koos volumio[9139]: info: MRS: Pushing multiroomSync output Apr 24 14:57:03 volumio-koos volumio[9139]: info: ------------------------------ 67ms Apr 24 14:57:03 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:03 volumio-koos volumio[9139]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Apr 24 14:57:03 volumio-koos volumio[9139]: info: TidalConnect service started! Apr 24 14:57:05 volumio-koos volumio[9139]: info: Executing endpoint metavolumio Apr 24 14:57:05 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 24 14:57:05 volumio-koos volumio[9139]: info: Executing endpoint metavolumio Apr 24 14:57:05 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 24 14:57:06 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:09 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:57:09 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:57:09 volumio-koos volumio[9139]: info: Discovery: Getting this device information Apr 24 14:57:09 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:09 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:57:10 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:14 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:18 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:22 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:24 volumio-koos volumio[9139]: info: Disabling MyMusic plugin bluetooth Apr 24 14:57:24 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Apr 24 14:57:24 volumio-koos sudo[11558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Apr 24 14:57:24 volumio-koos sudo[11558]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:24 volumio-koos systemd[1]: Stopping Volumio Bluetooth Module... Apr 24 14:57:24 volumio-koos volumio[9139]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 24 14:57:24 volumio-koos volumiobt[11564]: Killing pulse Apr 24 14:57:24 volumio-koos volumiobt[11564]: Killing BT Pair agent Apr 24 14:57:24 volumio-koos bluetoothd[649]: Endpoint unregistered: sender=:1.98 path=/MediaEndpoint/A2DPSource Apr 24 14:57:24 volumio-koos bluetoothd[649]: Endpoint unregistered: sender=:1.98 path=/MediaEndpoint/A2DPSink Apr 24 14:57:24 volumio-koos volumiobt[10972]: Terminated Apr 24 14:57:24 volumio-koos systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Apr 24 14:57:24 volumio-koos systemd[1]: volumiobt.service: Succeeded. Apr 24 14:57:24 volumio-koos systemd[1]: Stopped Volumio Bluetooth Module. Apr 24 14:57:24 volumio-koos sudo[11558]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:24 volumio-koos volumio[9139]: info: Volumio BT Module successfully stopped Apr 24 14:57:24 volumio-koos volumio[9139]: info: Disabling plugin bluetooth Apr 24 14:57:26 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:30 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:32 volumio-koos volumio[9139]: info: Enabling MyMusic plugin bluetooth Apr 24 14:57:32 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Apr 24 14:57:32 volumio-koos volumio[9139]: info: Enabling plugin bluetooth Apr 24 14:57:32 volumio-koos volumio[9139]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 24 14:57:32 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 14:57:32 volumio-koos volumio[9139]: info: Done. Apr 24 14:57:32 volumio-koos sudo[11586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio koos Apr 24 14:57:32 volumio-koos sudo[11586]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:33 volumio-koos sudo[11586]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:33 volumio-koos volumio[9139]: info: Bluetooth name changed to Volumio koos Apr 24 14:57:34 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:35 volumio-koos sudo[11597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 24 14:57:35 volumio-koos sudo[11597]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:35 volumio-koos systemd[1]: Started Volumio Bluetooth Module. Apr 24 14:57:35 volumio-koos sudo[11597]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:35 volumio-koos sudo[11604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 24 14:57:35 volumio-koos volumio[9139]: info: Volumio BT Module successfully started Apr 24 14:57:35 volumio-koos sudo[11604]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:35 volumio-koos volumiobt[11603]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 24 14:57:35 volumio-koos sudo[11604]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:36 volumio-koos sudo[11617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 24 14:57:36 volumio-koos sudo[11617]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:36 volumio-koos volumiobt[11603]: pulseaudio: no process found Apr 24 14:57:36 volumio-koos sudo[11617]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:37 volumio-koos pulseaudio[11628]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 24 14:57:37 volumio-koos pulseaudio[11628]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 24 14:57:37 volumio-koos volumiobt[11603]: Applying permissions Apr 24 14:57:37 volumio-koos volumiobt[11603]: Setting BT discoverable and pairable Apr 24 14:57:37 volumio-koos bluetoothd[649]: Endpoint registered: sender=:1.129 path=/MediaEndpoint/A2DPSource Apr 24 14:57:37 volumio-koos bluetoothd[649]: Endpoint registered: sender=:1.129 path=/MediaEndpoint/A2DPSink Apr 24 14:57:37 volumio-koos pulseaudio[11628]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 24 14:57:37 volumio-koos volumiobt[11603]: Agent registered Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [117B blob data] Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# -e power on Apr 24 14:57:37 volumio-koos volumiobt[11603]: Invalid command in menu main: -e Apr 24 14:57:37 volumio-koos volumiobt[11603]: Apr 24 14:57:37 volumio-koos volumiobt[11603]: Use "help" for a list of available commands in a menu. Apr 24 14:57:37 volumio-koos volumiobt[11603]: Use "menu " if you want to enter any submenu. Apr 24 14:57:37 volumio-koos volumiobt[11603]: Use "back" if you want to return to menu main. Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# agent on Apr 24 14:57:37 volumio-koos volumiobt[11603]: Agent is already registered Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# discoverable on Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# pairable on Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# agent NoInputNoOutput Apr 24 14:57:37 volumio-koos volumiobt[11603]: Agent is already registered Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# default-agent Apr 24 14:57:37 volumio-koos volumiobt[11603]: [bluetooth]# quit Apr 24 14:57:37 volumio-koos volumiobt[11603]: [59B blob data] Apr 24 14:57:38 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:57:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:57:39 volumio-koos volumio[9139]: info: Discovery: Getting this device information Apr 24 14:57:39 volumio-koos volumio[9139]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:39 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:57:42 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:46 volumio-koos volumio[9139]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 24 14:57:47 volumio-koos vtcs[11496]: [2025-04-24 14:57:47.911] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 24 14:57:47 volumio-koos go-librespot[10104]: time="2025-04-24T14:57:47+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Apr 24 14:57:47 volumio-koos systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Apr 24 14:57:47 volumio-koos volumio-remote-updater[602]: [2025-04-24 12:57:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 24 14:57:47 volumio-koos volumio-remote-updater[602]: [2025-04-24 12:57:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 24 14:57:47 volumio-koos systemd[1]: volumio.service: Failed with result 'signal'. Apr 24 14:57:47 volumio-koos systemd[1]: Started dynamicswap service. Apr 24 14:57:47 volumio-koos systemd[1]: dynamicswap.service: Succeeded. Apr 24 14:57:48 volumio-koos systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 24 14:57:48 volumio-koos systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 24 14:57:48 volumio-koos systemd[1]: Started dynamicswap service. Apr 24 14:57:48 volumio-koos systemd[1]: Stopped Volumio Backend Module. Apr 24 14:57:48 volumio-koos systemd[1]: Started Volumio Backend Module. Apr 24 14:57:48 volumio-koos systemd[1]: dynamicswap.service: Succeeded. Apr 24 14:57:49 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ----- Volumio3 ---- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ----- System startup ---- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:49 volumio-koos volumio[11657]: info: MYVOLUMIO Environment detected Apr 24 14:57:49 volumio-koos volumio[11657]: info: Plugin folders cleanup Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning into folder /volumio/app/plugins/ Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category audio_interface Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category miscellanea Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category music_service Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category plugins.json Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category system_controller Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category user_interface Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning into folder /data/plugins/ Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category music_service Apr 24 14:57:49 volumio-koos volumio[11657]: info: Scanning category system_controller Apr 24 14:57:49 volumio-koos volumio[11657]: info: Plugin folders cleanup completed Apr 24 14:57:49 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ----- Core plugins startup ---- Apr 24 14:57:49 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:49 volumio-koos volumio[11657]: info: Loading plugins from folder /volumio/app/plugins/ Apr 24 14:57:49 volumio-koos volumio[11657]: info: Adding plugin upnp to MyMusic Plugins Apr 24 14:57:49 volumio-koos volumio[11657]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 24 14:57:49 volumio-koos volumio[11657]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 24 14:57:49 volumio-koos volumio[11657]: info: Loading plugins from folder /data/plugins/ Apr 24 14:57:49 volumio-koos volumio[11657]: info: Loading plugin "system"... Apr 24 14:57:49 volumio-koos volumio[11657]: info: Loading plugin "ampswitch"... Apr 24 14:57:49 volumio-koos volumio[11657]: info: Applying required configuration parameters for plugin ampswitch Apr 24 14:57:49 volumio-koos volumio[11657]: info: Loading plugin "appearance"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "network"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Refreshing Cached IP Addresses Apr 24 14:57:50 volumio-koos sudo[11685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 14:57:50 volumio-koos sudo[11687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "services"... Apr 24 14:57:50 volumio-koos sudo[11685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "alsa_controller"... Apr 24 14:57:50 volumio-koos sudo[11685]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:50 volumio-koos sudo[11700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 24 14:57:50 volumio-koos sudo[11687]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:50 volumio-koos sudo[11700]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:50 volumio-koos sudo[11687]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:50 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "wizard"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "networkfs"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Starting Udev Watcher for removable devices Apr 24 14:57:50 volumio-koos volumio[11657]: info: Ignoring mount for partition: BOOT Apr 24 14:57:50 volumio-koos volumio[11657]: info: Ignoring mount for partition: volumio Apr 24 14:57:50 volumio-koos volumio[11657]: info: Ignoring mount for partition: volumio_data Apr 24 14:57:50 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "volumio_command_line_client"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "upnp"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: [1745499470598] Starting Upmpd Daemon Apr 24 14:57:50 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "my_music"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "mpd"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "upnp_browser"... Apr 24 14:57:50 volumio-koos volumio[11657]: info: Loading plugin "alarm-clock"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "airplay_emulation"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Starting Shairport Sync Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "last_100"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "webradio"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "i2s_dacs"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: I2S DAC not set, start Auto-detection Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "volumiodiscovery"... Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** For more information see Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** For more information see Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 14:57:51 volumio-koos volumio[11657]: *** WARNING *** For more information see Apr 24 14:57:51 volumio-koos node[11657]: *** WARNING *** For more information see Apr 24 14:57:51 volumio-koos volumio[11657]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 24 14:57:51 volumio-koos volumio[11657]: info: Discovery: Started advertising with name: Volumio koos Apr 24 14:57:51 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "spop"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "outputs"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "albumart"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Plugin example_plugin is not enabled Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "inputs"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "updater_comm"... Apr 24 14:57:51 volumio-koos sudo[11700]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:51 volumio-koos volumio[11657]: info: Plugin mpdemulation is not enabled Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "rest_api"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading plugin "websocket"... Apr 24 14:57:51 volumio-koos volumio[11657]: info: Starting Socket.io Server version 2.3.0 Apr 24 14:57:51 volumio-koos volumio[11657]: info: Loading i18n strings for locale nl Apr 24 14:57:51 volumio-koos volumio[11657]: Updating browse sources language Apr 24 14:57:51 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::initPlayerControls Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: Express server listening on port 3000 Apr 24 14:57:52 volumio-koos volumio[11657]: [Metrics] WebUI: 3s 166.68ms Apr 24 14:57:52 volumio-koos volumio[11657]: Forking 3 albumart workers Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::resetVolumioState Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::getcurrentVolume Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 24 14:57:52 volumio-koos volumio[11657]: info: Completed loading Core Plugins Apr 24 14:57:52 volumio-koos volumio[11657]: info: Preparing to generate the ALSA configuration file Apr 24 14:57:52 volumio-koos volumio[11657]: info: Volumio Network Manager: Network status updated: 1 Apr 24 14:57:52 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 1 Apr 24 14:57:52 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 24 14:57:52 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 24 14:57:52 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 24 14:57:52 volumio-koos volumio[11657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 24 14:57:52 volumio-koos volumio[11657]: info: Reloading queue from file Apr 24 14:57:52 volumio-koos volumio[11657]: info: Asound.conf file unchanged, so no further update is needed Apr 24 14:57:52 volumio-koos volumio[11657]: info: Output device has changed, restarting MPD Apr 24 14:57:52 volumio-koos volumio[11657]: info: Output device has changed, restarting Shairport Sync Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:52 volumio-koos sudo[11781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 14:57:52 volumio-koos sudo[11782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 14:57:52 volumio-koos sudo[11781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:52 volumio-koos volumio[11657]: info: ___________ START PLUGINS ___________ Apr 24 14:57:52 volumio-koos sudo[11781]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:52 volumio-koos sudo[11782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:52 volumio-koos kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 24 14:57:52 volumio-koos volumio[11657]: info: ControllerMpd::onStart: Initializing MPD Apr 24 14:57:52 volumio-koos volumio[11657]: info: Creating MPD Configuration file Apr 24 14:57:52 volumio-koos systemd[1]: Stopping Music Player Daemon... Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 14:57:52 volumio-koos volumio[11657]: info: [1745499472600] CoreMusicLibrary::Adding element Media Servers Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:52 volumio-koos sudo[11806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:52 volumio-koos sudo[11808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 14:57:52 volumio-koos sudo[11806]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:52 volumio-koos systemd[1]: mpd.service: Succeeded. Apr 24 14:57:52 volumio-koos systemd[1]: Stopped Music Player Daemon. Apr 24 14:57:52 volumio-koos systemd[1]: Starting Music Player Daemon... Apr 24 14:57:52 volumio-koos sudo[11808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:52 volumio-koos sudo[11806]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:52 volumio-koos systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 24 14:57:52 volumio-koos systemd[1]: mpd.service: Succeeded. Apr 24 14:57:52 volumio-koos systemd[1]: Stopped Music Player Daemon. Apr 24 14:57:52 volumio-koos systemd[1]: Starting Music Player Daemon... Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 14:57:52 volumio-koos volumio[11657]: info: [1745499472756] CoreMusicLibrary::Adding element Last_100 Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 14:57:52 volumio-koos volumio[11657]: info: [1745499472760] CoreMusicLibrary::Adding element Webradio Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 14:57:52 volumio-koos volumio[11657]: info: Initializing BBC Radios Apr 24 14:57:52 volumio-koos volumio[11657]: Starting albumart workers Apr 24 14:57:52 volumio-koos volumio[11657]: Starting albumart workers Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:52 volumio-koos sudo[11828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 14:57:52 volumio-koos volumio[11657]: Starting albumart workers Apr 24 14:57:52 volumio-koos volumio[11657]: info: Creating Spotify config file Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:52 volumio-koos volumio[11657]: info: Volumio Calling Home Apr 24 14:57:52 volumio-koos sudo[11828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:52 volumio-koos sudo[11828]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:52 volumio-koos volumio-remote-updater[602]: [2025-04-24 12:57:52] [connect] Successful connection Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::setRepeat null single undefined Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::pushState Apr 24 14:57:52 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::setRandom null Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreStateMachine::pushState Apr 24 14:57:52 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:52 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:52 volumio-koos volumio[11657]: info: Setting Device type: Tinkerboard Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 24 14:57:53 volumio-koos volumio[11657]: info: VolumeController:: Volume=34 Mute =false Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreStateMachine::pushState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreStateMachine::updateTrackBlock Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrackBlock Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: Getting this device information Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 Apr 24 14:57:53 volumio-koos volumio[11657]: info: MPD Permissions set Apr 24 14:57:53 volumio-koos volumio[11657]: info: MPD Permissions set Apr 24 14:57:53 volumio-koos volumio-remote-updater[602]: [2025-04-24 12:57:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745499472 101 Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Apr 24 14:57:53 volumio-koos volumio[11657]: info: Volumio called home Apr 24 14:57:53 volumio-koos volumio[11657]: info: Spotify config file written Apr 24 14:57:53 volumio-koos sudo[11857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 24 14:57:53 volumio-koos sudo[11857]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 24 14:57:53 volumio-koos systemd[1]: Stopping go-librespot Daemon... Apr 24 14:57:53 volumio-koos systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Apr 24 14:57:53 volumio-koos systemd[1]: go-librespot-daemon.service: Succeeded. Apr 24 14:57:53 volumio-koos systemd[1]: Stopped go-librespot Daemon. Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199:3000 from 192.168.178.66 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos systemd[1]: Started go-librespot Daemon. Apr 24 14:57:53 volumio-koos sudo[11857]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:53 volumio-koos go-librespot[11879]: go-librespot daemon starting... Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=info msg="running go-librespot 0.2.0" Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=debug msg="app state loaded" Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=debug msg="stored credentials not found" Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 14:57:53 volumio-koos volumio[11657]: info: No need to fix Spotify hosts Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: adding 304fdbd3-c9eb-4063-ba35-66cc2aefdb0a Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: Found device Volumio koos Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 24 14:57:53 volumio-koos volumio[11657]: verbose: New Socket.io Connection to 192.168.178.199 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 24 14:57:53 volumio-koos volumio[11657]: info: VolumeController:: Volume=34 Mute =false Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreStateMachine::pushState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioPushState Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: this is already registered, 304fdbd3-c9eb-4063-ba35-66cc2aefdb0a Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: Found device Volumio koos Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos go-librespot[11879]: time="2025-04-24T14:57:53+02:00" level=info msg="zeroconf server listening on port 43253" Apr 24 14:57:53 volumio-koos volumio[11657]: info: Received Get System Info Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: Getting this device information Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 14:57:53 volumio-koos volumio[11657]: info: Received Get System Info Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 14:57:53 volumio-koos volumio[11657]: info: Discovery: Getting this device information Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos volumio[11657]: info: Listing playlists Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetQueue Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreStateMachine::getQueue Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getQueue Apr 24 14:57:53 volumio-koos volumio[11657]: info: Starting Shairport Sync Apr 24 14:57:53 volumio-koos volumio[11657]: info: Starting Shairport Sync Apr 24 14:57:53 volumio-koos volumio[11657]: info: Starting Shairport Sync Apr 24 14:57:53 volumio-koos sudo[11916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:57:53 volumio-koos sudo[11916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:53 volumio-koos volumio[11657]: info: CoreCommandRouter::volumioGetState Apr 24 14:57:53 volumio-koos volumio[11657]: info: CorePlayQueue::getTrack 0 Apr 24 14:57:53 volumio-koos sudo[11922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:57:53 volumio-koos sudo[11920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] CurState: stop PrevState: na Apr 24 14:57:53 volumio-koos volumio[11657]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 14:57:53 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:57:53 volumio-koos sudo[11922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:53 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:57:53 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:53 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:53 volumio-koos sudo[11916]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:53 volumio-koos sudo[11920]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 14:57:53 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:57:53 volumio-koos systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 24 14:57:53 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:57:53 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:53 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:53 volumio-koos sudo[11922]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:53 volumio-koos volumio[11657]: info: Shairport-Sync Started Apr 24 14:57:53 volumio-koos volumio[11657]: Error adding Membership: Error: addMembership EINVAL Apr 24 14:57:53 volumio-koos systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 14:57:53 volumio-koos systemd[1]: shairport-sync.service: Succeeded. Apr 24 14:57:53 volumio-koos systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:54 volumio-koos systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 14:57:54 volumio-koos sudo[11920]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:54 volumio-koos volumio[11657]: info: Shairport-Sync Started Apr 24 14:57:54 volumio-koos volumio[11657]: info: Shairport-Sync Started Apr 24 14:57:54 volumio-koos mpd[11851]: Apr 24 14:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 14:57:54 volumio-koos systemd[1]: Started Music Player Daemon. Apr 24 14:57:54 volumio-koos sudo[11808]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:54 volumio-koos sudo[11782]: pam_unix(sudo:session): session closed for user root Apr 24 14:57:54 volumio-koos volumio[11657]: info: Completed starting Core Plugins Apr 24 14:57:54 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:54 volumio-koos volumio[11657]: info: ----- MyVolumio plugins startup ---- Apr 24 14:57:54 volumio-koos volumio[11657]: info: ------------------------------------------- Apr 24 14:57:54 volumio-koos volumio[11657]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 24 14:57:54 volumio-koos volumio[11657]: error: MPD error: The expression evaluated to a falsy value: Apr 24 14:57:54 volumio-koos volumio[11657]: assert.ok(self.idling) Apr 24 14:57:54 volumio-koos volumio[11657]: error: The expression evaluated to a falsy value: Apr 24 14:57:54 volumio-koos volumio[11657]: assert.ok(self.idling) Apr 24 14:57:54 volumio-koos volumio[11657]: error: updateQueue error: null Apr 24 14:57:54 volumio-koos volumio[11657]: info: MPD running with PID11851 Apr 24 14:57:54 volumio-koos volumio[11657]: ,establishing connection Apr 24 14:57:54 volumio-koos volumio[11657]: error: updateQueue error: null Apr 24 14:57:54 volumio-koos volumio[11657]: info: [ASDebug] Togle GPIO: OFF Apr 24 14:57:54 volumio-koos volumio[11657]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 14:57:54 volumio-koos volumio[11657]: Error: EPERM: operation not permitted, write Apr 24 14:57:54 volumio-koos volumio[11657]: at Object.writeSync (fs.js:737:3) Apr 24 14:57:54 volumio-koos volumio[11657]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 24 14:57:54 volumio-koos volumio[11657]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 24 14:57:54 volumio-koos volumio[11657]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 24 14:57:54 volumio-koos volumio[11657]: at listOnTimeout (internal/timers.js:557:17) Apr 24 14:57:54 volumio-koos volumio[11657]: at processTimers (internal/timers.js:500:7) { Apr 24 14:57:54 volumio-koos volumio[11657]: errno: -1, Apr 24 14:57:54 volumio-koos volumio[11657]: syscall: 'write', Apr 24 14:57:54 volumio-koos volumio[11657]: code: 'EPERM' Apr 24 14:57:54 volumio-koos volumio[11657]: } Apr 24 14:57:54 volumio-koos volumio[11657]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 14:57:54 volumio-koos sudo[11970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 14:56 Apr 24 14:57:54 volumio-koos sudo[11970]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:14:40 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="a9bbde8819c9c7e405795a261e2a2f93"