Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "spop"... Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "multiroom"... Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Applying required configuration parameters for plugin multiroom Aug 26 15:07:00 ferrum-broen-pg sudo[8655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 26 15:07:00 ferrum-broen-pg sudo[8655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:00 ferrum-broen-pg sudo[8655]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: MRS: MultiRoom plugin initialized Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: MRS: STOPPING SNAPCLIENT Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: MRS: Snap server stop Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: MRS: STOPPING volumioStreaming Aug 26 15:07:00 ferrum-broen-pg sudo[8672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 26 15:07:00 ferrum-broen-pg sudo[8672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "outputs"... Aug 26 15:07:00 ferrum-broen-pg sudo[8677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "albumart"... Aug 26 15:07:00 ferrum-broen-pg sudo[8677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:00 ferrum-broen-pg sudo[8679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 26 15:07:00 ferrum-broen-pg sudo[8679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:00 ferrum-broen-pg sudo[8679]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:00 ferrum-broen-pg sudo[8674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 26 15:07:00 ferrum-broen-pg sudo[8674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Plugin example_plugin is not enabled Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "hi_res_audio"... Aug 26 15:07:00 ferrum-broen-pg sudo[8672]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:00 ferrum-broen-pg sudo[8674]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:00 ferrum-broen-pg sudo[8677]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:00 ferrum-broen-pg volumio[8682]: Forking 3 albumart workers Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Applying required configuration parameters for plugin hi_res_audio Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "inputs"... Aug 26 15:07:00 ferrum-broen-pg volumio[8585]: info: Loading plugin "qobuz"... Aug 26 15:07:01 ferrum-broen-pg volumio[8695]: Starting albumart workers Aug 26 15:07:01 ferrum-broen-pg volumio[8694]: Starting albumart workers Aug 26 15:07:01 ferrum-broen-pg volumio[8693]: Starting albumart workers Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "tidal"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "ferrumbroencontrol"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Device already connected Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Device state: CONNECTED Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Applying required configuration parameters for plugin ferrumbroencontrol Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM] Adding this device properties Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Setting Additional Device Volumio Properties: [object Object] Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Additional UI Settings Added for plugin system_controller/ferrumbroencontrol Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Additional UI Settings Added for plugin system_controller/ferrumbroencontrol Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , registerCustomCommandHandler Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM] Plugin started successfully. Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "oem_helper"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Applying required configuration parameters for plugin oem_helper Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "updater_comm"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "ferrum_streaming_control_technology"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Plugin mpdemulation is not enabled Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "rest_api"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading plugin "websocket"... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Starting Socket.io Server version 1.7.4 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Loading i18n strings for locale en Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: Updating browse sources language Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::initPlayerControls Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: Express server listening on port 3000 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: [Metrics] WebUI: 3s 384.45ms Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::resetVolumioState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::getcurrentVolume Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Info updated: {"firmwareVersion":"0.5.1","hardwareVersion":"3.0","bootloaderVersion":"0.15.1","serialNumber":"serce=HEM021509","connected":true} Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Device state: READY Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM] SDDC attached, checking for updates... Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Volumio Network Manager: Network status updated: 2 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: MRS: Removed streaming files Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: MRS: volumioStreaming STOPPED Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: MRS: SNAPSERVER STOPPED Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: MRS: SNAPCLIENT STOPPED Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Starting check for all targets... (force=false) Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: 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 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Reloading queue from file Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 Safari/537.36 OPR/134.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setRepeat null single undefined Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setRandom null Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 0 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Setting Device type: Raspberry PI Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: USB Boot Capable - System SBC Revision found in cpuinfo: e04180 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 Safari/537.36 OPR/134.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Bootloader - Current: 0.15.1, Available: 0.15.1 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Bootloader is up to date. Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 1 to 2 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 Safari/537.36 OPR/134.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 2 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Completed loading Core Plugins Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Preparing to generate the ALSA configuration file Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Reading ALSA contributions from plugins. Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 3 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 4 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Application - Current: 0.5.1, Available: 0.5.1 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Application is up to date. Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target MCU is not connected, skipping. Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 5 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 6 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 7 to 3 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: [HEM:SDDC] Setting config 8 to 0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Asound.conf file unchanged, so no further update is needed Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Output device has changed, restarting MPD Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Output device has changed, restarting Shairport Sync Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:01 ferrum-broen-pg sudo[8760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 15:07:01 ferrum-broen-pg sudo[8760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:01 ferrum-broen-pg sudo[8762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 15:07:01 ferrum-broen-pg sudo[8760]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:01 ferrum-broen-pg sudo[8762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 15:07:01 ferrum-broen-pg volumio[8585]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: ___________ START PLUGINS ___________ Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::onStart: Initializing MPD Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Creating MPD Configuration file Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 15:07:02 ferrum-broen-pg sudo[8772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 26 15:07:02 ferrum-broen-pg sudo[8772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [1787749622021] CoreMusicLibrary::Adding element Media Servers Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg sudo[8774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 15:07:02 ferrum-broen-pg sudo[8774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: UPNP Browser: Client initialized successfully Aug 26 15:07:02 ferrum-broen-pg sudo[8774]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 26 15:07:02 ferrum-broen-pg sudo[8777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 15:07:02 ferrum-broen-pg sudo[8777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8772]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding METAVOLUMIO REST API Endpoints Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg systemd[1]: mpd.service: Deactivated successfully. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: mpd.socket: Deactivated successfully. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 15:07:02 ferrum-broen-pg bluetoothd[1002]: Path / reserved for Adv Monitor app :1.150 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Preparing CD Folders Aug 26 15:07:02 ferrum-broen-pg bluetoothd[1002]: Adv Monitor app :1.150 disconnected from D-Bus Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding CD REST API Endpoints Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Starting UDEV Watcher for CD Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Detecting CD presence with UDEV Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [1787749622081] CoreMusicLibrary::Adding element Last_100 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Streaming services startup Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Starting Streaming Daemon Aug 26 15:07:02 ferrum-broen-pg sudo[8797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 26 15:07:02 ferrum-broen-pg sudo[8797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 15:07:02 ferrum-broen-pg sudo[8799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [1787749622114] CoreMusicLibrary::Adding element Webradio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Initializing BBC Radios Aug 26 15:07:02 ferrum-broen-pg sudo[8807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 26 15:07:02 ferrum-broen-pg sudo[8799]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg sudo[8807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8797]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg sudo[8791]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 15:07:02 ferrum-broen-pg sudo[8791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 15:07:02 ferrum-broen-pg sudo[8791]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg sudo[8813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 26 15:07:02 ferrum-broen-pg sudo[8813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Getting this device information Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [1787749622151] CoreMusicLibrary::Adding element Jellyfin Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: Cannot find translation for source Jellyfin Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.11","id":"c21581c8-a5fb-4f18-a83e-46acf62a239f","name":"Ferrum Broen PG","serviceName":"Volumio","state":{"albumart":"http://192.168.1.11/albumart?sourceicon=music_service%2Fjellyfin%2Fdist%2Fassets%2Fimages%2Fsong.png","artist":"","mute":false,"status":"stop","track":"Ain't No Love In The Heart Of The City","volume":100},"type":"device"} Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Creating Spotify config file Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg qobuz-connect[8400]: 20260826 15:07:02.164 [8400.8400] INFO SampleApp: Stopping Local configuration server Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: Hi Res Audio Failed Login: Missing Login Data Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Initializing Serial Communication on port /dev/ttyAMA3 Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 26 15:07:02 ferrum-broen-pg systemd[1]: vtcs.service: Killing process 8486 (vtcs) with signal SIGKILL. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Touch Event Listener Process Starting Aug 26 15:07:02 ferrum-broen-pg systemd[1]: vtcs.service: Deactivated successfully. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] Plugin onStart Aug 26 15:07:02 ferrum-broen-pg sudo[8830]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 26 15:07:02 ferrum-broen-pg sudo[8830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8807]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg fsctd[1949]: [2026-08-26T13:07:02Z INFO fsct_driver::ipc] New IPC client connected, id = 1d8e66d0-61f4-4624-9db0-c26263078462 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Volumio Calling Home Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Stopping AccessToken refresher cron for QOBUZ Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: AccessToken refresher cron started for QOBUZ Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding TIDAL REST API Endpoints Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding QOBUZ REST API Endpoints Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: adding c21581c8-a5fb-4f18-a83e-46acf62a239f Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Found device Ferrum Broen PG Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output for this device Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding audio output: Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Adding audio output: Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: this is already registered, c21581c8-a5fb-4f18-a83e-46acf62a239f Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Found device Ferrum Broen PG Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Serial port opened successfully Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Sending serial start messages Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Reporting MCU Network Status: 2 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: 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: 5 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: Cannot start Volumio Streaming Daemon Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MPD Permissions set Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MPD Permissions set Aug 26 15:07:02 ferrum-broen-pg sudo[8851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 26 15:07:02 ferrum-broen-pg sudo[8851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Spotify config file written Aug 26 15:07:02 ferrum-broen-pg sudo[8851]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg sudo[8854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 15:07:02 ferrum-broen-pg sudo[8854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: No need to fix Spotify hosts Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting Additional System Software info: Hardware Revision: 2.0, SN: HEM021887 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting HW Firmware info: undefined Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting HW Version info: 2.0, SN: HEM021887 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting Additional System Software info: Hardware Revision: 2.0, SN: HEM021887, Firmware Version: 0.26.0-Release Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting HW Firmware info: 0.26.0-Release Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Setting HW Version info: 2.0, SN: HEM021887 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled Sleep Mode Disabled Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] PWRSTATE received: 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Enabling Advanced system settings configuration Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Additional UI Settings Added for plugin music_service/inputs Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled Auto Boot Mode On Power Active Aug 26 15:07:02 ferrum-broen-pg fsctd[1949]: [2026-08-26T13:07:02Z INFO fsct_driver::player_manager] Player 9 registered: com.hem-e.fsct-volumio-plugin Aug 26 15:07:02 ferrum-broen-pg fsctd[1949]: [2026-08-26T13:07:02Z INFO fsct_driver::ipc] Registered player 9 for connection 1d8e66d0-61f4-4624-9db0-c26263078462 Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 15:07:02 ferrum-broen-pg sudo[8854]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg go-librespot[8862]: go-librespot daemon starting... Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 15:07:02 ferrum-broen-pg sudo[8868]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 26 15:07:02 ferrum-broen-pg sudo[8868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8868]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 26 15:07:02 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:02+02:00" level=debug msg="app state loaded" Aug 26 15:07:02 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:02+02:00" level=debug msg="stored credentials not found" Aug 26 15:07:02 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [fsct] registered player id=9, protocol=1.0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Received Get System Info Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Getting this device information Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Listing playlists Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [fsct] FSCT Started Aug 26 15:07:02 ferrum-broen-pg sudo[8878]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 26 15:07:02 ferrum-broen-pg sudo[8878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Starting Shairport Sync Aug 26 15:07:02 ferrum-broen-pg sudo[8878]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Starting Shairport Sync Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Starting Shairport Sync Aug 26 15:07:02 ferrum-broen-pg sudo[8881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 15:07:02 ferrum-broen-pg sudo[8881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg sudo[8883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 15:07:02 ferrum-broen-pg sudo[8883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 26 15:07:02 ferrum-broen-pg sudo[8886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 15:07:02 ferrum-broen-pg sudo[8886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 15:07:02 ferrum-broen-pg systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: shairport-sync.service: Consumed 1.496s CPU time. Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 15:07:02 ferrum-broen-pg sudo[8886]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg sudo[8883]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/150.0.0.0 Safari/537.36 OPR/134.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 26 15:07:02 ferrum-broen-pg sudo[8881]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::getQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Listing playlists Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Received Get System Info Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Getting this device information Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Shairport-Sync Started Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: Error adding Membership: Error: addMembership EINVAL Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Shairport-Sync Started Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Shairport-Sync Started Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-poller] Polled http://192.168.1.2:8096: online Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Volumio called home Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Inactive Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg mpd[8820]: 2026-08-26T15:07:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 26 15:07:02 ferrum-broen-pg volumio-remote-updater[1032]: [2026-08-26 15:07:02] [connect] Successful connection Aug 26 15:07:02 ferrum-broen-pg volumio-remote-updater[1032]: [2026-08-26 15:07:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787749622 101 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 10 Aug 26 15:07:02 ferrum-broen-pg systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 15:07:02 ferrum-broen-pg sudo[8762]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg sudo[8777]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: MPD error: The expression evaluated to a falsy value: Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: assert.ok(self.idling) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: The expression evaluated to a falsy value: Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: assert.ok(self.idling) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: MPD running with PID8820 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: ,establishing connection Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager:MCU] MCU reported version 0.26.0-Release after 1316ms Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , sendSerialMessage Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , sendSerialMessage Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , sendSerialMessage Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , sendSerialMessage Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Received Get System Info Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Discovery: Getting this device information Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: Listing playlists Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::getQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getQueue Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] TRIGDIR received: INPUT Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] Checking for updates. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Starting check for all targets... (force=false) Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] TRIGINACTION received: 0 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] ACTLEDDIMM received: 20 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: inputs , sendSerialMessage Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM] STBYLEDDIMM received: 10 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Bootloader - Current: 0.15.1, Available: 0.15.1 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Bootloader is up to date. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Application - Current: 0.5.1, Available: 0.5.1 Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target SDDC Application is up to date. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target MCU - Current: 0.26.0-Release, Available: 0.26.0-Release Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: [HEM:FirmwareManager] Target MCU is up to date. Aug 26 15:07:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Aug 26 15:07:03 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 15:07:03 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 15:07:03 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 15:07:03 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:03+02:00" level=info msg="zeroconf server listening on port 34869" Aug 26 15:07:03 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 15:07:03 ferrum-broen-pg sudo[8624]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: [HEM:ExternalDevices] Skipping blocklisted/internal device 3100:0033 (HYPSOS) Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: [HEM:ExternalDevices] Skipping blocklisted/internal device 3336:0039 (SERCE DDC) Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: [HEM:ExternalDevices] Loaded 7 supported external device type(s). Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: [HEM:ExternalDevices] Periodic update check every 3600s Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: Received Get System Info Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: Discovery: Getting this device information Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 15:07:03 ferrum-broen-pg systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 26 15:07:03 ferrum-broen-pg systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 15:07:03 ferrum-broen-pg sudo[8813]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:03 ferrum-broen-pg systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: Executing endpoint qc_getconfig Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.723 [8915.8915] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.725 [8915.8915] INFO VolumeManager: [0x1e38158]: Setting new playback volume: 75 Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.725 [8915.8915] INFO VolumeManager: [0x1e38158]: Setting new mute state: 0 Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.725 [8915.8915] INFO AudioStreamManager: [0x1e37eb0]: Setting new audio download buffer size: 1048576 Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.725 [8915.8915] INFO QobuzConnect: [0x1e38a20]: Client initialized! Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.725 [8915.8915] INFO SampleApp: Starting Avahi advertising, name: Ferrum Broen PG, service name: _qobuz-connect._tcp Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.729 [8915.8915] INFO LocalConfigManager: [0x1e37bd8]: Starting Local Configuration server Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.729 [8915.8915] INFO SampleApp: Starting Local configuration server Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.729 [8915.8915] INFO SampleApp: Connected to UNIX socket client 0x1e22908 Aug 26 15:07:03 ferrum-broen-pg qobuz-connect[8915]: 20260826 15:07:03.873 [8915.8915] INFO SampleApp: Playback volume changed: 75 Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:03 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:04 ferrum-broen-pg volumio5-onboarding[4000]: time=2026-08-26T15:07:04.403+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: TidalConnect service stoped! Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 26 15:07:05 ferrum-broen-pg sudo[8930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 26 15:07:05 ferrum-broen-pg sudo[8930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:05 ferrum-broen-pg systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 26 15:07:05 ferrum-broen-pg sudo[8930]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Executing endpoint tc_getconfig Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 26 15:07:05 ferrum-broen-pg vtcs[8933]: STARTING TidalConnect services, version: 1.6.1 Aug 26 15:07:05 ferrum-broen-pg vtcs[8933]: STARTED TidalConnect services. Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Executing endpoint tc_connect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Connecting to TidalConnect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received tidalconnect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received tidalconnect Aug 26 15:07:05 ferrum-broen-pg volumio[8585]: info: go-librespot daemon successfully initialized Aug 26 15:07:07 ferrum-broen-pg volumio[8585]: info: MRS: Getting audio outputs on start Aug 26 15:07:07 ferrum-broen-pg volumio[8585]: info: MRS: Requesting all other devices output Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: TidalConnect service started! Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: Completed starting Core Plugins Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: ------------------------------------------- Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: ----- MyVolumio plugins startup ---- Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: ------------------------------------------- Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 15:07:08 ferrum-broen-pg volumio[8585]: info: Initializing connection to go-librespot Websocket Aug 26 15:07:08 ferrum-broen-pg go-librespot[8869]: time="2026-08-26T15:07:08+02:00" level=debug msg="new websocket client" Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Connection to go-librespot Websocket established Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::ClearQueue Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::clearPlayQueue Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::addQueueItems Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::addQueueItems Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Adding Item to queue: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218 Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Exploding uri jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218 in service jellyfin Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] explodeUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218 Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: [jellyfin-conn] Using previously assigned device Id for albert@SoundRite_Home: 40f20aa6-4a7a-4863-be32-3ef94f3a6eec Aug 26 15:07:09 ferrum-broen-pg sudo[8968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 15:07:09 ferrum-broen-pg sudo[8968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:09 ferrum-broen-pg sudo[8970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 15:07:09 ferrum-broen-pg sudo[8970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:09 ferrum-broen-pg sudo[8970]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:09 ferrum-broen-pg sudo[8968]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:09 ferrum-broen-pg sudo[8974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 15:07:09 ferrum-broen-pg sudo[8974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:09 ferrum-broen-pg sudo[8974]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: Upmpdcli Daemon Started Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: info: [jellyfin-conn] Login successful: albert@SoundRite_Home Aug 26 15:07:09 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"ddd1428c901ed768a487af2867e3d218","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&recursive=true&sortOrder=Ascending&parentId=ddd1428c901ed768a487af2867e3d218&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::updateTrackBlock Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrackBlock Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPlay Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index 0 Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index undefined Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::startPlaybackTimer Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=94b2586ef3dae2fa17cd36e80a31a736 Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] Stream URL for The Other Side: http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: sendMpdCommand stop took 0 milliseconds Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: sendMpdCommand clear took 0 milliseconds Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav" Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav" Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 45ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 44ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 44ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: sendMpdCommand addid "http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav" took 3 milliseconds Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 4ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 2ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 2ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand play Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: sendMpdCommand play took 2 milliseconds Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 2ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Mark song The Other Side as played by Albert. Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:10 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 20 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 20 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 20 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 19 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 3 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 25ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 74ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 73ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 55ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 61 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 60 milliseconds Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":435,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"The Other Side","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/94b2586ef3dae2fa17cd36e80a31a736/stream.wav?static=true&mediaSourceId=94b2586ef3dae2fa17cd36e80a31a736&tag=3ed6a1208f6dbefa6ae6233b769914ed&t.wav","trackType":"wav"} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 89ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: ------------------------------ 88ms Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Active Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'start' for song: The Other Side (at 0 ms) Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'timeupdate' for song: The Other Side (at 46 ms) Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218 Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"ddd1428c901ed768a487af2867e3d218","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 26 15:07:11 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&recursive=true&sortOrder=Ascending&parentId=ddd1428c901ed768a487af2867e3d218&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=94b2586ef3dae2fa17cd36e80a31a736 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=81a45d0e5236c0a2c03615b4979523cd Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=ebe7fe0d527ffeac257f012aef2dac06 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=40f6ccdca3993c3ed109bbbeb7dc1d5f Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=8f09214c6e07db10c7c6c0a8de559fcb Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=168be4252907d80329dbdbcefd734660 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=b9485c17ca1c4dbe0830ba8253d1f729 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=b8e3b08ce72281562931ce6565b7fa25 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=e2057261bc1d346a7420d5523dd19992 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/songs@albumId=a2fe8e8fbd303af3640a0a935634044a/songs@albumId=ddd1428c901ed768a487af2867e3d218/song@songId=e60b4f377fa37d3d9f831ebc6746fb1a Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Executing endpoint getSimilarAlbums Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Getting Spotify volume Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:07:12 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:07:13 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 15:07:16 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: MyVolumio login type: Token Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 26 15:07:17 ferrum-broen-pg volumio[8585]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: MyVolumio token set successfully Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: MYVOLUMIO: Adding device Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: MYVOLUMIO: Evaluating Server Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: MyVolumio status changed Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Streaming services startup Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Starting Streaming Daemon Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Removing browser output: myVolumio user plan is not superstar Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Removing audio output: Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Stoppping Tunnel 1 Aug 26 15:07:18 ferrum-broen-pg sudo[9038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 15:07:18 ferrum-broen-pg sudo[9038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:18 ferrum-broen-pg sudo[9040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 26 15:07:18 ferrum-broen-pg sudo[9040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 15:07:18 ferrum-broen-pg sudo[9038]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: error: Cannot start Volumio Streaming Daemon Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 15:07:18 ferrum-broen-pg sudo[9040]: pam_unix(sudo:session): session closed for user root Aug 26 15:07:18 ferrum-broen-pg volumio[8585]: info: Remote SSH Stopped Aug 26 15:07:20 ferrum-broen-pg volumio[8585]: error: Could not execute script Error: Command failed: bash -c "if [[ -f /data/configuration/music_service/spop/config.json ]]; then jq -c '.credentials_type.value = \"zeroconf\"' /data/configuration/music_service/spop/config.json > /tmp/spop.tmp && sleep 10 && mv /tmp/spop.tmp /data/configuration/music_service/spop/config.json; fi" Aug 26 15:07:20 ferrum-broen-pg volumio[8585]: mv: cannot stat '/tmp/spop.tmp': No such file or directory Aug 26 15:07:24 ferrum-broen-pg volumio[8585]: info: Setting Geolocation for MyVolumio to eu10 Aug 26 15:07:24 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:24 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:24 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:24 ferrum-broen-pg volumio[8585]: info: Successfully Added MyVolumio device Aug 26 15:07:25 ferrum-broen-pg volumio[8585]: info: Updating MyVolumio device info Aug 26 15:07:25 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:25 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:25 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:25 ferrum-broen-pg volumio[8585]: info: Successfully Updated MyVolumio device Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: error: MyVolumio Plugin failed to start in a timely fashion Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: [Metrics] CommandRouter: 29s 900.82ms Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumiosetStartupVolume Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::Close All Modals sent Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::Close All Modals sent Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Aug 26 15:07:28 ferrum-broen-pg volumio[8585]: aplay: main:831: audio open error: Device or resource busy Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioNext Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::next Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::next Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand next Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand next took 17 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 0 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index undefined Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::startPlaybackTimer Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=81a45d0e5236c0a2c03615b4979523cd Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 21ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 15ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 16ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: error: [fsct] updatePlayerState failed: invalid player state: invalid type: null, expected u64 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=94b2586ef3dae2fa17cd36e80a31a736","favourite":false} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'stop' for song: The Other Side (at 18129 ms) Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] Stream URL for Young Lust: http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand stop took 0 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand clear took 0 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav" Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Inactive Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav" Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 36ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 36ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand addid "http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav" took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 2ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 3ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand play took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Mark song Young Lust as played by Albert. Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 17 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 19 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 19 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 18 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 20ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 3 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 86ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 86ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 68ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 82 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 82 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 81 milliseconds Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":389,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 128ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: ------------------------------ 126ms Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Active Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'start' for song: Young Lust (at 91 ms) Aug 26 15:07:29 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'timeupdate' for song: Young Lust (at 160 ms) Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 15:07:32 ferrum-broen-pg volumio-remote-updater[1032]: Test mode disabled Aug 26 15:07:32 ferrum-broen-pg volumio-remote-updater[1032]: Alpha mode enabled Aug 26 15:07:32 ferrum-broen-pg volumio-remote-updater[1032]: Alpha legacy test mode disabled Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v4.201","updateavailable":true} Aug 26 15:07:32 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 26 15:07:35 ferrum-broen-pg volumio[8585]: info: BOOT COMPLETED Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 15:07:37 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 15:07:46 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:46 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin Aug 26 15:07:46 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9 Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/UserViews?userId=83318ffba4144310bde6826501aa4d5d Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:47 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5 Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":5,"sortBy":["DatePlayed","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":5,"sortBy":["PlayCount","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":5,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"filters":["IsFavorite"],"includeItemTypes":["Audio"]} Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=DatePlayed&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=5&recursive=true&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&fields=MediaSources&includeItemTypes=Audio&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=5&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=PlayCount&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Artists?startIndex=0&limit=5&parentId=148c709a75698abc5e97b303f715b8e5&filters=IsFavorite&imageTypeLimit=1&enableImageTypes=Primary&userId=83318ffba4144310bde6826501aa4d5d&sortBy=SortName&sortOrder=Ascending Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=81a45d0e5236c0a2c03615b4979523cd Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=94b2586ef3dae2fa17cd36e80a31a736 Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=94b2586ef3dae2fa17cd36e80a31a736 Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Preloading song: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=81a45d0e5236c0a2c03615b4979523cd Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: Exploding uri jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=94b2586ef3dae2fa17cd36e80a31a736 in service jellyfin Aug 26 15:07:49 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] explodeUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=94b2586ef3dae2fa17cd36e80a31a736 Aug 26 15:07:50 ferrum-broen-pg volumio[8585]: info: Exploding uri jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=81a45d0e5236c0a2c03615b4979523cd in service jellyfin Aug 26 15:07:50 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] explodeUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/song@songId=81a45d0e5236c0a2c03615b4979523cd Aug 26 15:07:52 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:52 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@noExplode=1 Aug 26 15:07:52 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":0,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:52 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:52 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:54 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:54 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=47@noExplode=1 Aug 26 15:07:54 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":47,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:55 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=47&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:55 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:07:57 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 26 15:07:57 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] browseUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1 Aug 26 15:07:57 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"148c709a75698abc5e97b303f715b8e5","startIndex":94,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 26 15:07:57 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=94&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=148c709a75698abc5e97b303f715b8e5&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:07:57 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::ClearQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stPlaybackTimer Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::updateTrackBlock Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrackBlock Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::serviceStop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::serviceStop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::clearPlayQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::addQueueItems Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::addQueueItems Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Adding Item to queue: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=8d1577fef65f861e0e86507269dd33cf Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Exploding uri jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=8d1577fef65f861e0e86507269dd33cf in service jellyfin Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] explodeUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=8d1577fef65f861e0e86507269dd33cf Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"8d1577fef65f861e0e86507269dd33cf","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand stop took 41 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 0 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 9 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 8 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 42ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Young Lust","artist":"Aerosmith","album":"Pump","uri":"http://192.168.1.2:8096/Audio/81a45d0e5236c0a2c03615b4979523cd/stream.wav?static=true&mediaSourceId=81a45d0e5236c0a2c03615b4979523cd&tag=aad5ff80c61935c393b2a0334262380a&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 1 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 111ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 112ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Inactive Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=81a45d0e5236c0a2c03615b4979523cd","favourite":false} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Active Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&recursive=true&sortOrder=Ascending&parentId=8d1577fef65f861e0e86507269dd33cf&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::updateTrackBlock Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrackBlock Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPlay Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index undefined Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::startPlaybackTimer Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=e6525531638a2f7cc85278204aefdad5 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'stop' for song: Young Lust (at 32803 ms) Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] Stream URL for Imperium: http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand stop took 0 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand clear took 0 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav" Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav" Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: error: updateQueue error: null Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 15ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 14ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand addid "http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav" took 0 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: MPD COMMAND [object Object] Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces system playlist update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Ignoring MPD Status Update Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 1ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand play took 0 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 3ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 3ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Mark song Imperium as played by Albert. Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 15 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 15 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 14 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 14 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 3 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 4 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 29ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 61ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 60ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 55 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 56 milliseconds Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":100,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus play Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Received an update from plugin. extracting info from payload Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 92ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 92ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: ------------------------------ 91ms Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:02 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'timeupdate' for song: Imperium (at 1237 ms) Aug 26 15:08:03 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'start' for song: Imperium (at 0 ms) Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: Executing endpoint metavolumio Aug 26 15:08:04 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::ClearQueue Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stPlaybackTimer Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::updateTrackBlock Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrackBlock Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::serviceStop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::serviceStop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::clearPlayQueue Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::addQueueItems Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::addQueueItems Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Preload queue cleared Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Adding Item to queue: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=e245b18522a1714630cc04ffacd7bee7 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Exploding uri jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=e245b18522a1714630cc04ffacd7bee7 in service jellyfin Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: [jellyfin-browse] explodeUri: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/library@parentId=148c709a75698abc5e97b303f715b8e5/albums@parentId=148c709a75698abc5e97b303f715b8e5@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=94@noExplode=1/songs@albumId=e245b18522a1714630cc04ffacd7bee7 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"83318ffba4144310bde6826501aa4d5d","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"e245b18522a1714630cc04ffacd7bee7","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand stop took 44 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: ---------------------------- MPD announces state update: player Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::getState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand status Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 2 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand status took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: ControllerMpd::parseTrackInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: ControllerMpd::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::servicePushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Imperium","artist":"Ghost","album":"Impera","uri":"http://192.168.1.2:8096/Audio/e6525531638a2f7cc85278204aefdad5/stream.wav?static=true&mediaSourceId=e6525531638a2f7cc85278204aefdad5&tag=02a30623e3492abd4e7e7a18a75e952d&t.wav","trackType":"wav"} Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: verbose: CURRENT POSITION 0 Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState stateService stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::syncState currentStatus stop Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: No code Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::pushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioGetState Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output update for this device Aug 26 15:08:05 ferrum-broen-pg volumio[8585]: info: MRS: Pushing multiroomSync output Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: ------------------------------ 63ms Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: ------------------------------ 62ms Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: ------------------------------ 63ms Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"favourite":false} Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Signalling Playback active due to playback status change Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=e6525531638a2f7cc85278204aefdad5","favourite":false} Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Inactive Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: MCU Signalled Playback Active Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: [jellyfin-play]: Reported 'stop' for song: Imperium (at 4498 ms) Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.2:8096/Items?userId=83318ffba4144310bde6826501aa4d5d&startIndex=0&recursive=true&sortOrder=Ascending&parentId=e245b18522a1714630cc04ffacd7bee7&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPushQueue Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::saveQueue Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::updateTrackBlock Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrackBlock Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreCommandRouter::volumioPlay Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index 0 Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::stop Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::play index undefined Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CoreStateMachine::startPlaybackTimer Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: CorePlayQueue::getTrack 0 Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/albert@70bbadd967264792812e7a3c484b24f9/song@songId=46e1ba4ba7b16b0807ce7a31a7021782 Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: Error: No media streams found for song All The World Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: at PlayController._PlayController_getStreamUrl (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:241:15) Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:78:234) Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 26 15:08:06 ferrum-broen-pg volumio[8585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 15:08:06 ferrum-broen-pg sudo[9263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 15:07' Aug 26 15:08:06 ferrum-broen-pg sudo[9263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ad2b65e62ee66106fc9799a5d56d019303babfad" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="ferrumbroen" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 17:09:13 UTC 2026" VOLUMIO_VERSION="4.197" VOLUMIO_HARDWARE="cm5" VOLUMIO_DEVICENAME="CM5" VOLUMIO_HASH="dc49e901e04215cf1cb417367be636b9"