-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-12-29 22:05:40 CET. -- Dec 29 22:04:00 harsmuziek volumio[830]: info: MyVolumio token set successfully Dec 29 22:04:00 harsmuziek volumio[830]: info: MYVOLUMIO: Adding device Dec 29 22:04:00 harsmuziek volumio[830]: info: MYVOLUMIO: Evaluating Server Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 22:04:00 harsmuziek volumio[830]: info: MyVolumio Plan changed: premium Dec 29 22:04:00 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 29 22:04:00 harsmuziek volumio[830]: info: Removing browser output: myVolumio user plan is not superstar Dec 29 22:04:00 harsmuziek volumio[830]: info: Removing audio output: Dec 29 22:04:00 harsmuziek volumio[830]: info: MYVOLUMIO: Adding device Dec 29 22:04:00 harsmuziek volumio[830]: info: MYVOLUMIO: Evaluating Server Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 29 22:04:00 harsmuziek volumio[830]: info: Remote config written successfully Dec 29 22:04:00 harsmuziek volumio[830]: info: Starting Tunnel 1 Dec 29 22:04:00 harsmuziek volumio[830]: info: Starting Tunnel Connection Checker Dec 29 22:04:00 harsmuziek volumio[830]: info: Completed starting MyVolumio Plugin Dec 29 22:04:00 harsmuziek volumio[830]: info: MYVolumio Device enabled Dec 29 22:04:00 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 29 22:04:00 harsmuziek volumio[830]: info: MyVolumio status changed Dec 29 22:04:00 harsmuziek volumio[830]: info: Streaming services startup Dec 29 22:04:00 harsmuziek volumio[830]: info: Starting Streaming Daemon Dec 29 22:04:00 harsmuziek volumio[830]: info: Setting Geolocation for MyVolumio to eu2 Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:00 harsmuziek sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 29 22:04:00 harsmuziek sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:00 harsmuziek sudo[1475]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:00 harsmuziek volumio[830]: error: Cannot start Volumio Streaming Daemon Dec 29 22:04:00 harsmuziek volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 29 22:04:00 harsmuziek volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 29 22:04:00 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 29 22:04:00 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 29 22:04:01 harsmuziek volumio[830]: info: [1767042241092] Starting BluetoothController Dec 29 22:04:01 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 29 22:04:02 harsmuziek volumio[1309]: ........................................................................................................++++ Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 29 22:04:02 harsmuziek volumio[1309]: ..++++ Dec 29 22:04:02 harsmuziek volumio[1309]: e is 65537 (0x010001) Dec 29 22:04:02 harsmuziek sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 29 22:04:02 harsmuziek sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:02 harsmuziek sudo[1492]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:02 harsmuziek volumio[1309]: writing RSA key Dec 29 22:04:02 harsmuziek volumio[830]: info: MRS: MultiRoom plugin initialized Dec 29 22:04:02 harsmuziek volumio[830]: info: MRS: STOPPING SNAPCLIENT Dec 29 22:04:02 harsmuziek volumio[830]: info: MRS: Snap server stop Dec 29 22:04:02 harsmuziek sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 29 22:04:02 harsmuziek volumio[830]: info: MRS: STOPPING volumioStreaming Dec 29 22:04:02 harsmuziek sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:02 harsmuziek sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 29 22:04:02 harsmuziek sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:02 harsmuziek sudo[1519]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:02 harsmuziek sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 29 22:04:02 harsmuziek sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:02 harsmuziek sudo[1524]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 29 22:04:02 harsmuziek sudo[1521]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 29 22:04:02 harsmuziek sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 29 22:04:02 harsmuziek sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:02 harsmuziek sudo[1542]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 29 22:04:02 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 29 22:04:03 harsmuziek volumio[830]: info: Preparing to generate the ALSA configuration file Dec 29 22:04:03 harsmuziek volumio[830]: info: Updating MyVolumio device info Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 29 22:04:03 harsmuziek volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 29 22:04:03 harsmuziek volumio[830]: info: Reading ALSA contributions from plugins. Dec 29 22:04:03 harsmuziek volumio[830]: info: Setting Geolocation for MyVolumio to eu2 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: Removed streaming files Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: volumioStreaming STOPPED Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: SNAPSERVER STOPPED Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: SNAPCLIENT STOPPED Dec 29 22:04:03 harsmuziek volumio[830]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 29 22:04:03 harsmuziek volumio[830]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 22:04:03 harsmuziek volumio[830]: info: Asound.conf file written Dec 29 22:04:03 harsmuziek sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 29 22:04:03 harsmuziek sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek sudo[1547]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek volumio[830]: info: Output device has changed, restarting MPD Dec 29 22:04:03 harsmuziek sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 29 22:04:03 harsmuziek sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek volumio[830]: info: Output device has changed, restarting Shairport Sync Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:03 harsmuziek sudo[1552]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 29 22:04:03 harsmuziek sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek systemd[1]: Stopping Music Player Daemon... Dec 29 22:04:03 harsmuziek volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:03 harsmuziek systemd[1]: mpd.service: Succeeded. Dec 29 22:04:03 harsmuziek systemd[1]: Stopped Music Player Daemon. Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 29 22:04:03 harsmuziek systemd[1]: Starting Music Player Daemon... Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output for this device Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:03 harsmuziek volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 29 22:04:03 harsmuziek sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name HarsMuziek Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:03 harsmuziek sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek sudo[1564]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding METAVOLUMIO REST API Endpoints Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 29 22:04:03 harsmuziek volumio[830]: info: Preparing CD Folders Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding CD REST API Endpoints Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 29 22:04:03 harsmuziek volumio[830]: info: Starting UDEV Watcher for CD Dec 29 22:04:03 harsmuziek volumio[830]: info: Detecting CD presence with UDEV Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding inputs REST Endpoints Dec 29 22:04:03 harsmuziek sudo[1561]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 29 22:04:03 harsmuziek sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 29 22:04:03 harsmuziek volumio[830]: info: Scanning Audio Inputs Dec 29 22:04:03 harsmuziek sudo[1561]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek volumio[830]: info: Checking against Known Cards name Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 29 22:04:03 harsmuziek volumio[830]: info: [1767042243621] CoreMusicLibrary::Adding element HiFiBerry ADC Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 22:04:03 harsmuziek volumio[830]: Cannot find translation for source HiFiBerry ADC Dec 29 22:04:03 harsmuziek volumio[830]: info: Checking against Known Cards name Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding Server instance for streaming Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 29 22:04:03 harsmuziek volumio[830]: error: Hi Res Audio Failed Login: Missing Login Data Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 29 22:04:03 harsmuziek volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 29 22:04:03 harsmuziek sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 29 22:04:03 harsmuziek sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek volumio[830]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 29 22:04:03 harsmuziek sudo[1574]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 29 22:04:03 harsmuziek sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek volumio[830]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding TIDAL REST API Endpoints Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 29 22:04:03 harsmuziek volumio[830]: info: Stopping AccessToken refresher cron for QOBUZ Dec 29 22:04:03 harsmuziek sudo[1580]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 29 22:04:03 harsmuziek sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:03 harsmuziek volumio[830]: info: AccessToken refresher cron started for QOBUZ Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding QOBUZ REST API Endpoints Dec 29 22:04:03 harsmuziek volumio[830]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 22:04:03 harsmuziek volumio[830]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 29 22:04:03 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:04:03 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: Listing playlists Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:04:03 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:04:03 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:04:03 harsmuziek systemd[1]: Started Volumio Qobuz Connect Service. Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 22:04:03 harsmuziek volumio[830]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 29 22:04:03 harsmuziek sudo[1583]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:03 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:04:03 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:04:03 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:03 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:04 harsmuziek volumio[830]: info: Listing playlists Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 29 22:04:04 harsmuziek volumio[830]: info: Successfully Updated MyVolumio device Dec 29 22:04:04 harsmuziek volumio[830]: info: Successfully Added MyVolumio device Dec 29 22:04:04 harsmuziek volumio[830]: info: Successfully Added MyVolumio device Dec 29 22:04:04 harsmuziek volumio[830]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'HarsMuziek' Dec 29 22:04:04 harsmuziek volumio[830]: Can't get device info: No such device Dec 29 22:04:04 harsmuziek volumio[830]: info: MPD Permissions set Dec 29 22:04:04 harsmuziek volumio[830]: info: Executing endpoint qc_getconfig Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.212 [1585.1585] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 29 22:04:04 harsmuziek volumio[830]: info: Updating MyVolumio device info Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 29 22:04:04 harsmuziek volumio[830]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.314 [1585.1585] INFO VolumeManager: [0x1982a38]: Setting new playback volume: 75 Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.314 [1585.1585] INFO VolumeManager: [0x1982a38]: Setting new mute state: 0 Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.314 [1585.1585] INFO QobuzConnect: [0x1983408]: Client initialized! Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.314 [1585.1585] INFO SampleApp: Starting Avahi advertising, name: HarsMuziek, service name: _qobuz-connect._tcp Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.330 [1585.1585] INFO LocalConfigManager: [0x1982318]: Starting Local Configuration server Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.331 [1585.1585] INFO SampleApp: Starting Local configuration server Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.331 [1585.1585] INFO SampleApp: Connected to UNIX socket client 0x1977f60 Dec 29 22:04:04 harsmuziek qobuz-connect[1585]: 20251229 22:04:04.362 [1585.1585] INFO SampleApp: Playback volume changed: 75 Dec 29 22:04:04 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:04 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:04 harsmuziek volumio[830]: info: Starting Shairport Sync Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: Google-Home-01ccc38282fa501b5536beee5ea70dad Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: X04G-93cc583f9d24e4b67c8b17f146ce68c8 Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 22:04:04 harsmuziek sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: Chromecast-HD-0c9b8fb9f290402a5990cf039d61d254 Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 29 22:04:04 harsmuziek systemd[1]: shairport-sync.service: Succeeded. Dec 29 22:04:04 harsmuziek systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: Thomson-TV-6ac82fea91fbdef23f25832bc96c84aa Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: KPN-DIW7022-53e6b9e595251d4ad0226bbbfcb57785 Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek volumio[830]: info: MRS: Found cast device: KPN-DIW7022-d73a2338c7c63f58a74fe17a64c4420a Dec 29 22:04:04 harsmuziek volumio[830]: info: Adding audio output: Dec 29 22:04:04 harsmuziek systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 29 22:04:04 harsmuziek sudo[1600]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:04 harsmuziek volumio[830]: info: Shairport-Sync Started Dec 29 22:04:04 harsmuziek mpd[1571]: Dec 29 22:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 29 22:04:04 harsmuziek systemd[1]: Started Music Player Daemon. Dec 29 22:04:04 harsmuziek sudo[1554]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:04 harsmuziek volumio[830]: error: updateQueue error: null Dec 29 22:04:04 harsmuziek volumio[830]: info: Successfully Updated MyVolumio device Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:04:05 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:04:05 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:05 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:04:05 harsmuziek sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 29 22:04:05 harsmuziek sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:05 harsmuziek systemd[1]: Started MyVolumio SSH Tunnel. Dec 29 22:04:05 harsmuziek sudo[1613]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:05 harsmuziek volumio[830]: info: Remote SSH Started Dec 29 22:04:05 harsmuziek autossh[1616]: port set to 0, monitoring disabled Dec 29 22:04:05 harsmuziek autossh[1616]: starting ssh (count 1) Dec 29 22:04:05 harsmuziek autossh[1616]: ssh child pid is 1619 Dec 29 22:04:05 harsmuziek volumiossh-tunnel[1615]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Dec 29 22:04:05 harsmuziek volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 29 22:04:05 harsmuziek systemd[1]: systemd-fsckd.service: Succeeded. Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:05 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 29 22:04:06 harsmuziek sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 29 22:04:06 harsmuziek sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:06 harsmuziek systemd[1]: Started Volumio Bluetooth Module. Dec 29 22:04:06 harsmuziek sudo[1623]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:06 harsmuziek volumio[830]: info: Volumio BT Module successfully started Dec 29 22:04:06 harsmuziek sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 29 22:04:06 harsmuziek sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:06 harsmuziek volumiobt[1627]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 29 22:04:06 harsmuziek sudo[1628]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:06 harsmuziek volumio[830]: info: TidalConnect service stoped! Dec 29 22:04:06 harsmuziek volumio[830]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 29 22:04:06 harsmuziek volumio[830]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 29 22:04:06 harsmuziek sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 29 22:04:06 harsmuziek sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:06 harsmuziek systemd[1]: Started Volumio Tidal Connect Service. Dec 29 22:04:06 harsmuziek sudo[1640]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:07 harsmuziek volumio[830]: info: Executing endpoint tc_getconfig Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 29 22:04:07 harsmuziek vtcs[1642]: STARTING TidalConnect services, version: 1.5.2.56 Dec 29 22:04:07 harsmuziek vtcs[1642]: STARTED TidalConnect services. Dec 29 22:04:07 harsmuziek volumio[830]: info: Executing endpoint tc_connect Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 29 22:04:07 harsmuziek volumio[830]: info: Connecting to TidalConnect Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 29 22:04:07 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: No code Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 29 22:04:07 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: No code Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:04:07 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:04:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:07 harsmuziek sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 29 22:04:07 harsmuziek sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:07 harsmuziek sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 29 22:04:07 harsmuziek sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:04:07 harsmuziek systemd[1]: Stopping MPD Monitor Service... Dec 29 22:04:07 harsmuziek systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 29 22:04:07 harsmuziek systemd[1]: mpd_monitor.service: Succeeded. Dec 29 22:04:07 harsmuziek systemd[1]: Stopped MPD Monitor Service. Dec 29 22:04:07 harsmuziek volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 29 22:04:07 harsmuziek volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 29 22:04:07 harsmuziek volumiobt[1627]: pulseaudio: no process found Dec 29 22:04:07 harsmuziek sudo[1662]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:07 harsmuziek systemd[1]: Started MPD Monitor Service. Dec 29 22:04:07 harsmuziek mpd_monitor.sh[1666]: MPD Monitor Service: Starting MPD Monitor Service Dec 29 22:04:07 harsmuziek sudo[1663]: pam_unix(sudo:session): session closed for user root Dec 29 22:04:07 harsmuziek volumio[830]: info: Successfully started MPD Monitor Dec 29 22:04:07 harsmuziek dbus-daemon[590]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.32' (uid=1000 pid=1683 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 29 22:04:07 harsmuziek systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 29 22:04:07 harsmuziek volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 29 22:04:07 harsmuziek dbus-daemon[590]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Successfully called chroot. Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Successfully dropped privileges. Dec 29 22:04:07 harsmuziek systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Successfully limited resources. Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Running. Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Canary thread running. Dec 29 22:04:07 harsmuziek rtkit-daemon[1687]: Watchdog thread running. Dec 29 22:04:07 harsmuziek dbus-daemon[590]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.33' (uid=0 pid=1687 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 29 22:04:07 harsmuziek systemd[1]: Starting Authorization Manager... Dec 29 22:04:07 harsmuziek volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 29 22:04:07 harsmuziek polkitd[1695]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 29 22:04:07 harsmuziek dbus-daemon[590]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 29 22:04:07 harsmuziek systemd[1]: Started Authorization Manager. Dec 29 22:04:08 harsmuziek pulseaudio[1683]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 29 22:04:08 harsmuziek pulseaudio[1683]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 29 22:04:08 harsmuziek volumiobt[1627]: Applying permissions Dec 29 22:04:08 harsmuziek volumiobt[1627]: Setting BT discoverable and pairable Dec 29 22:04:08 harsmuziek pulseaudio[1683]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Dec 29 22:04:08 harsmuziek volumiobt[1627]: [79B blob data] Dec 29 22:04:08 harsmuziek volumiobt[1627]: Invalid command in menu main: -e Dec 29 22:04:08 harsmuziek volumiobt[1627]: Dec 29 22:04:08 harsmuziek volumiobt[1627]: Use "help" for a list of available commands in a menu. Dec 29 22:04:08 harsmuziek volumiobt[1627]: Use "menu " if you want to enter any submenu. Dec 29 22:04:08 harsmuziek volumiobt[1627]: Use "back" if you want to return to menu main. Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> agent on Dec 29 22:04:08 harsmuziek volumiobt[1627]: Failed to register agent object Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> discoverable on Dec 29 22:04:08 harsmuziek volumiobt[1627]: No default controller available Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> pairable on Dec 29 22:04:08 harsmuziek volumiobt[1627]: No default controller available Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> agent NoInputNoOutput Dec 29 22:04:08 harsmuziek volumiobt[1627]: Failed to register agent object Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> default-agent Dec 29 22:04:08 harsmuziek volumiobt[1627]: No agent is registered Dec 29 22:04:08 harsmuziek volumiobt[1627]: [bluetoothctl]> quit Dec 29 22:04:08 harsmuziek volumiobt[1627]: [65B blob data] Dec 29 22:04:08 harsmuziek volumio[830]: info: MRS: Getting audio outputs on start Dec 29 22:04:08 harsmuziek volumio[830]: info: MRS: Requesting all other devices output Dec 29 22:04:09 harsmuziek volumio[830]: info: TidalConnect service started! Dec 29 22:04:09 harsmuziek volumio[830]: [Metrics] CommandRouter: 29s 383.68ms Dec 29 22:04:09 harsmuziek volumio[830]: info: CoreCommandRouter::volumiosetStartupVolume Dec 29 22:04:09 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:09 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:09 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:04:09 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:04:10 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 29 22:04:10 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 29 22:04:10 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 29 22:04:11 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 29 22:04:12 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:04:12 harsmuziek volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 29 22:04:12 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:12 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 22:04:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 22:04:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 22:04:16 harsmuziek volumio-remote-updater[592]: No test mode Dec 29 22:04:16 harsmuziek volumio-remote-updater[592]: No alpha test mode Dec 29 22:04:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 29 22:04:16 harsmuziek volumio[830]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 29 22:04:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 29 22:04:16 harsmuziek volumio[830]: info: BOOT COMPLETED Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:04:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 22:04:18 harsmuziek systemd[1]: systemd-timedated.service: Succeeded. Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:04:18 harsmuziek volumio[830]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 22:04:18 harsmuziek volumio[830]: info: Received Get System Version Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 22:04:18 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:04:18 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:04:18 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:04:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:04:43 harsmuziek ntpd[798]: Soliciting pool server 178.239.19.63 Dec 29 22:04:44 harsmuziek ntpd[798]: Soliciting pool server 178.239.19.61 Dec 29 22:04:45 harsmuziek ntpd[798]: Soliciting pool server 162.159.200.1 Dec 29 22:04:45 harsmuziek ntpd[798]: Soliciting pool server 195.35.113.80 Dec 29 22:04:45 harsmuziek ntpd[798]: Soliciting pool server 188.90.28.184 Dec 29 22:04:46 harsmuziek ntpd[798]: Soliciting pool server 172.233.59.163 Dec 29 22:04:46 harsmuziek ntpd[798]: Soliciting pool server 185.71.60.123 Dec 29 22:04:46 harsmuziek ntpd[798]: Soliciting pool server 103.149.168.181 Dec 29 22:04:47 harsmuziek ntpd[798]: Soliciting pool server 77.175.129.186 Dec 29 22:04:47 harsmuziek ntpd[798]: Soliciting pool server 103.147.153.176 Dec 29 22:04:47 harsmuziek ntpd[798]: Soliciting pool server 178.239.19.62 Dec 29 22:04:48 harsmuziek ntpd[798]: Soliciting pool server 94.198.159.15 Dec 29 22:04:48 harsmuziek ntpd[798]: Soliciting pool server 138.124.3.169 Dec 29 22:04:48 harsmuziek ntpd[798]: Soliciting pool server 45.138.55.60 Dec 29 22:04:49 harsmuziek ntpd[798]: Soliciting pool server 145.53.74.170 Dec 29 22:04:50 harsmuziek ntpd[798]: Soliciting pool server 31.58.102.164 Dec 29 22:04:51 harsmuziek ntpd[798]: Soliciting pool server 2600:3c0e::f03c:94ff:fe79:be07 Dec 29 22:04:54 harsmuziek ntpd[798]: receive: Unexpected origin timestamp 0xecfd6b75.82e911e6 does not match aorg 0000000000.00000000 from server@45.138.55.60 xmt 0xecfd6b76.f063da21 Dec 29 22:04:54 harsmuziek ntpd[798]: receive: Unexpected origin timestamp 0xecfd6b75.82e3f21b does not match aorg 0000000000.00000000 from server@31.58.102.164 xmt 0xecfd6b76.f0bf4d02 Dec 29 22:04:54 harsmuziek ntpd[798]: receive: Unexpected origin timestamp 0xecfd6b75.82ebc0f7 does not match aorg 0000000000.00000000 from server@103.149.168.181 xmt 0xecfd6b76.f07c7d12 Dec 29 22:05:05 harsmuziek volumio[830]: verbose: New Socket.io Connection to tkmj7kgkmonb8irkjmyvcybbwtc2-62a92d4c686db49fbbaa664ce381bc2c.eu2.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 8 Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:05 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetQueue Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreStateMachine::getQueue Dec 29 22:05:05 harsmuziek volumio[830]: info: CorePlayQueue::getQueue Dec 29 22:05:05 harsmuziek volumio[830]: info: Listing playlists Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 29 22:05:05 harsmuziek volumio[830]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 29 22:05:05 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:05:05 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:05 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:05 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:05:05 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 29 22:05:06 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:05:06 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:05:06 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:05:06 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:05:06 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:05:06 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:06 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:06 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:05:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:05:07 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:05:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:05:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:05:07 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:05:07 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:07 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:07 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:05:13 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:13 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:05:14 harsmuziek volumio[830]: info: Retrieving Cloud Streaming UI Dec 29 22:05:14 harsmuziek volumio[830]: info: Getting Tidal Cloud Configuration Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:05:14 harsmuziek volumio[830]: info: Getting Qobuz Cloud Configuration Dec 29 22:05:14 harsmuziek volumio[830]: info: Asking plugin for UI Config Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:05:14 harsmuziek volumio[830]: info: Getting Spotify Cloud Configuration Dec 29 22:05:14 harsmuziek volumio[830]: info: Asking plugin for UI Config Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:05:14 harsmuziek volumio[830]: info: Saving Spotify Acccount Dec 29 22:05:14 harsmuziek volumio[830]: info: Got it Dec 29 22:05:14 harsmuziek volumio[830]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 29 22:05:14 harsmuziek volumio[830]: info: Got Tidal Cloud Configuration Dec 29 22:05:14 harsmuziek volumio[830]: info: Got it Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:05:14 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:05:15 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 29 22:05:16 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand rescan Dec 29 22:05:16 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:16 harsmuziek volumio[830]: info: Dec 29 22:05:16 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:16 harsmuziek volumio[830]: info: sendMpdCommand rescan took 8 milliseconds Dec 29 22:05:16 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:16 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:16 harsmuziek volumio[830]: info: sendMpdCommand status took 6 milliseconds Dec 29 22:05:16 harsmuziek volumio[830]: info: sendMpdCommand status took 2 milliseconds Dec 29 22:05:16 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:16 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatetrue Dec 29 22:05:16 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:16 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 22:05:16 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: No code Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:16 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:16 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:16 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:16 harsmuziek volumio[830]: info: ------------------------------ 129ms Dec 29 22:05:17 harsmuziek volumio[830]: info: MPD Database updated - AlbumList cache refreshed Dec 29 22:05:17 harsmuziek volumio[830]: info: Dec 29 22:05:17 harsmuziek volumio[830]: ---------------------------- MPD announces state update: database Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:17 harsmuziek volumio[830]: info: Dec 29 22:05:17 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:17 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:17 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:17 harsmuziek volumio[830]: info: sendMpdCommand status took 11 milliseconds Dec 29 22:05:17 harsmuziek volumio[830]: info: sendMpdCommand status took 10 milliseconds Dec 29 22:05:17 harsmuziek volumio[830]: info: sendMpdCommand status took 11 milliseconds Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:17 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatefalse Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:17 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:17 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: 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} Dec 29 22:05:17 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: No code Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: 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} Dec 29 22:05:17 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: No code Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:17 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:17 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:17 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:17 harsmuziek volumio[830]: info: ------------------------------ 190ms Dec 29 22:05:17 harsmuziek volumio[830]: info: ------------------------------ 191ms Dec 29 22:05:18 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand update Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:19 harsmuziek volumio[830]: info: Dec 29 22:05:19 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:19 harsmuziek volumio[830]: info: sendMpdCommand update took 5 milliseconds Dec 29 22:05:19 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:19 harsmuziek volumio[830]: info: sendMpdCommand status took 4 milliseconds Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:19 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatetrue Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:05:19 harsmuziek volumio[830]: info: sendMpdCommand status took 67 milliseconds Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:19 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 22:05:19 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: No code Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: ------------------------------ 127ms Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:19 harsmuziek volumio[830]: info: Dec 29 22:05:19 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:19 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:19 harsmuziek volumio[830]: info: sendMpdCommand status took 3 milliseconds Dec 29 22:05:19 harsmuziek volumio[830]: info: sendMpdCommand status took 3 milliseconds Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:19 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatefalse Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:05:19 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:19 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: 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} Dec 29 22:05:19 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: No code Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:19 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:19 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:19 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:19 harsmuziek volumio[830]: info: ------------------------------ 98ms Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand update Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:21 harsmuziek volumio[830]: info: Dec 29 22:05:21 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:21 harsmuziek volumio[830]: info: sendMpdCommand update took 5 milliseconds Dec 29 22:05:21 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:21 harsmuziek volumio[830]: info: sendMpdCommand status took 4 milliseconds Dec 29 22:05:21 harsmuziek volumio[830]: info: sendMpdCommand status took 1 milliseconds Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:21 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatetrue Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:21 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 22:05:21 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: No code Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: ------------------------------ 101ms Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:21 harsmuziek volumio[830]: info: Dec 29 22:05:21 harsmuziek volumio[830]: ---------------------------- MPD announces state update: update Dec 29 22:05:21 harsmuziek volumio[830]: info: ControllerMpd::getState Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:05:21 harsmuziek volumio[830]: info: sendMpdCommand status took 14 milliseconds Dec 29 22:05:21 harsmuziek volumio[830]: info: sendMpdCommand status took 13 milliseconds Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:21 harsmuziek volumio[830]: info: Command Router : Notfying DB Updatefalse Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:05:21 harsmuziek volumio[830]: verbose: ControllerMpd::parseState Dec 29 22:05:21 harsmuziek volumio[830]: info: ControllerMpd::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::servicePushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: 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} Dec 29 22:05:21 harsmuziek volumio[830]: verbose: CURRENT POSITION 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::syncState stateService stop Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::syncState currentStatus stop Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: No code Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreStateMachine::pushState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioPushState Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output update for this device Dec 29 22:05:21 harsmuziek volumio[830]: info: MRS: Pushing multiroomSync output Dec 29 22:05:21 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:21 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:21 harsmuziek volumio[830]: info: ------------------------------ 103ms Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:05:22 harsmuziek volumio[830]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 22:05:22 harsmuziek volumio[830]: info: Received Get System Version Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 22:05:22 harsmuziek volumio[830]: info: Received Get System Info Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:05:22 harsmuziek volumio[830]: info: Discovery: Getting this device information Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:22 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:05:22 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:05:27 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:05:30 harsmuziek volumio[830]: info: CoreCommandRouter::volumioGetState Dec 29 22:05:30 harsmuziek volumio[830]: info: CorePlayQueue::getTrack 0 Dec 29 22:05:36 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 22:05:36 harsmuziek volumio[830]: info: CURURI: albums:// Dec 29 22:05:36 harsmuziek volumio[830]: info: listAlbums - loading Albums from cache Dec 29 22:05:36 harsmuziek volumio[830]: info: Preload queue cleared Dec 29 22:05:39 harsmuziek volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 22:05:39 harsmuziek volumio[830]: info: CURURI: artists:// Dec 29 22:05:40 harsmuziek volumio[830]: info: Preload queue cleared Dec 29 22:05:40 harsmuziek volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 22:05:40 harsmuziek volumio[830]: TypeError: Cannot read property 'slice' of null Dec 29 22:05:40 harsmuziek volumio[830]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 29 22:05:40 harsmuziek volumio[830]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 29 22:05:40 harsmuziek volumio[830]: at Object.onceWrapper (events.js:422:26) Dec 29 22:05:40 harsmuziek volumio[830]: at ClientRequest.emit (events.js:315:20) Dec 29 22:05:40 harsmuziek volumio[830]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 29 22:05:40 harsmuziek volumio[830]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 29 22:05:40 harsmuziek volumio[830]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 29 22:05:40 harsmuziek volumio[830]: at TLSSocket.emit (events.js:315:20) Dec 29 22:05:40 harsmuziek volumio[830]: at addChunk (internal/streams/readable.js:309:12) Dec 29 22:05:40 harsmuziek volumio[830]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 29 22:05:40 harsmuziek volumio[830]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 29 22:05:40 harsmuziek volumio[830]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 29 22:05:40 harsmuziek volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 22:05:40 harsmuziek sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 22:04 Dec 29 22:05:40 harsmuziek sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"