-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Fri 2024-07-12 16:35:21 EDT. -- Jul 12 16:34:01 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:01 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:01 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:01 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:05 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:05 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: info: Retrieving Cloud Streaming UI Jul 12 16:34:10 volumioberry volumio[6091]: info: Getting Tidal Cloud Configuration Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: info: Getting Qobuz Cloud Configuration Jul 12 16:34:10 volumioberry volumio[6091]: info: Asking plugin for UI Config Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: info: Getting Spotify Cloud Configuration Jul 12 16:34:10 volumioberry volumio[6091]: info: Asking plugin for UI Config Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: info: Saving Spotify Acccount Jul 12 16:34:10 volumioberry volumio[6091]: info: Got Tidal Cloud Configuration Jul 12 16:34:10 volumioberry volumio[6091]: info: Got it Jul 12 16:34:10 volumioberry volumio[6091]: info: Got it Jul 12 16:34:10 volumioberry volumio[6091]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:10 volumioberry volumio[6091]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetBrowseSources Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 16:34:10 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 12 16:34:11 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:11 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:14 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 16:34:18 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 16:34:21 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:21 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:21 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:21 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:22 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 16:34:26 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 12 16:34:28 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:28 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:31 volumioberry volumio[6091]: info: Getting Alsa Cards List without I2S DAC Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: Invalid card number. Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 16:34:31 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:31 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:39 volumioberry volumio[6091]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 12 16:34:39 volumioberry volumio[6091]: info: Preparing to save Alsa Options, stopping services first Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:39 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPause Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreStateMachine::pause Jul 12 16:34:39 volumioberry volumio[6091]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"hifiberry-dacplus,slave","label":"HiFiBerry DAC Plus"}} Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:39 volumioberry volumio[6091]: info: Disabling I2S DAC: Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:39 volumioberry volumio[6091]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 12 16:34:39 volumioberry volumio[6091]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 12 16:34:39 volumioberry volumio[6091]: info: Disabling external Volume Control Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:39 volumioberry volumio[6091]: info: Enable softmixer device for audio device undefined Jul 12 16:34:39 volumioberry sudo[6726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 12 16:34:39 volumioberry sudo[6726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:39 volumioberry sudo[6726]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:39 volumioberry sudo[6729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r hifiberry-dacplus Jul 12 16:34:39 volumioberry sudo[6729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:39 volumioberry kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /dacpro_osc Jul 12 16:34:39 volumioberry kernel: OF: ERROR: memory leak before free overlay changeset, /dacpro_osc Jul 12 16:34:39 volumioberry sudo[6729]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:39 volumioberry volumio[6091]: info: Removed overlay hifiberry-dacplus Jul 12 16:34:39 volumioberry volumio[6091]: info: Software Volume ALSA configuration written Jul 12 16:34:39 volumioberry volumio[6091]: info: Preparing to generate the ALSA configuration file Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 12 16:34:39 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 16:34:40 volumioberry volumio[6091]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 12 16:34:40 volumioberry volumio[6091]: info: Reading ALSA contributions from plugins. Jul 12 16:34:40 volumioberry volumio[6091]: info: Asound.conf file written Jul 12 16:34:40 volumioberry sudo[6748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 12 16:34:40 volumioberry sudo[6748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry sudo[6748]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry volumio[6091]: No state is present for card b1 Jul 12 16:34:40 volumioberry volumio[6091]: /usr/share/alsa/init/bcm2835:14: control element not found Jul 12 16:34:40 volumioberry volumio[6091]: No state is present for card b1 Jul 12 16:34:40 volumioberry volumio[6091]: No state is present for card Headphones Jul 12 16:34:40 volumioberry volumio[6091]: /usr/share/alsa/init/bcm2835:20: control element not found Jul 12 16:34:40 volumioberry volumio[6091]: No state is present for card Headphones Jul 12 16:34:40 volumioberry volumio[6091]: info: Output device has changed, restarting MPD Jul 12 16:34:40 volumioberry sudo[6753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 16:34:40 volumioberry sudo[6753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry volumio[6091]: info: Output device has changed, restarting Shairport Sync Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:40 volumioberry sudo[6753]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry sudo[6756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 16:34:40 volumioberry sudo[6756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry systemd[1]: Stopping Music Player Daemon... Jul 12 16:34:40 volumioberry volumio[6091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 16:34:40 volumioberry volumio[6091]: info: Output device has changed, restarting MPD Jul 12 16:34:40 volumioberry systemd[1]: mpd.service: Succeeded. Jul 12 16:34:40 volumioberry systemd[1]: Stopped Music Player Daemon. Jul 12 16:34:40 volumioberry sudo[6763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 16:34:40 volumioberry sudo[6763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry volumio[6091]: info: Output device has changed, restarting Shairport Sync Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:40 volumioberry sudo[6763]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry systemd[1]: Starting Music Player Daemon... Jul 12 16:34:40 volumioberry sudo[6765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 16:34:40 volumioberry sudo[6765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 12 16:34:40 volumioberry systemd[1]: mpd.service: Succeeded. Jul 12 16:34:40 volumioberry systemd[1]: Stopped Music Player Daemon. Jul 12 16:34:40 volumioberry systemd[1]: Starting Music Player Daemon... Jul 12 16:34:40 volumioberry volumio[6091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 16:34:40 volumioberry volumio[6091]: info: MPD Permissions set Jul 12 16:34:40 volumioberry volumio[6091]: info: MPD Permissions set Jul 12 16:34:40 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:40 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:40 volumioberry volumio[6091]: info: Starting Shairport Sync Jul 12 16:34:40 volumioberry volumio[6091]: info: Starting Shairport Sync Jul 12 16:34:40 volumioberry sudo[6771]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 16:34:40 volumioberry sudo[6771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry sudo[6771]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry sudo[6781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 16:34:40 volumioberry sudo[6781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry sudo[6784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 16:34:40 volumioberry sudo[6784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:40 volumioberry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 16:34:40 volumioberry systemd[1]: shairport-sync.service: Succeeded. Jul 12 16:34:40 volumioberry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 16:34:40 volumioberry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 16:34:40 volumioberry sudo[6784]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry volumio[6091]: info: Shairport-Sync Started Jul 12 16:34:40 volumioberry sudo[6781]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:40 volumioberry volumio[6091]: info: Shairport-Sync Started Jul 12 16:34:41 volumioberry volumio[6091]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 12 16:34:41 volumioberry volumio[6091]: info: Preparing to save Alsa Options, stopping services first Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:41 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPause Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreStateMachine::pause Jul 12 16:34:41 volumioberry volumio[6091]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:41 volumioberry volumio[6091]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 12 16:34:41 volumioberry volumio[6091]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 12 16:34:41 volumioberry volumio[6091]: info: Disabling external Volume Control Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 16:34:41 volumioberry volumio[6091]: info: Enable softmixer device for audio device undefined Jul 12 16:34:41 volumioberry volumio[6091]: info: Software Volume ALSA configuration written Jul 12 16:34:41 volumioberry volumio[6091]: info: Preparing to generate the ALSA configuration file Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:41 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 16:34:41 volumioberry volumio[6091]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 12 16:34:41 volumioberry volumio[6091]: info: Reading ALSA contributions from plugins. Jul 12 16:34:41 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:41 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:34:41 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:41 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:41 volumioberry volumio[6091]: info: Asound.conf file unchanged, so no further update is needed Jul 12 16:34:41 volumioberry volumio[6091]: info: Output device has changed, restarting MPD Jul 12 16:34:41 volumioberry sudo[6804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 16:34:41 volumioberry sudo[6804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:41 volumioberry volumio[6091]: info: Output device has changed, restarting Shairport Sync Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:41 volumioberry sudo[6804]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:41 volumioberry sudo[6806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 16:34:41 volumioberry sudo[6806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:41 volumioberry systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 12 16:34:41 volumioberry systemd[1]: mpd.service: Succeeded. Jul 12 16:34:41 volumioberry systemd[1]: Stopped Music Player Daemon. Jul 12 16:34:41 volumioberry volumio[6091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 16:34:41 volumioberry volumio[6091]: info: MPD Permissions set Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 16:34:41 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 16:34:42 volumioberry volumio[6091]: info: Starting Shairport Sync Jul 12 16:34:42 volumioberry systemd[1]: Starting Music Player Daemon... Jul 12 16:34:42 volumioberry sudo[6819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 16:34:42 volumioberry sudo[6819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:42 volumioberry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 16:34:42 volumioberry systemd[1]: shairport-sync.service: Succeeded. Jul 12 16:34:42 volumioberry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 16:34:42 volumioberry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 16:34:42 volumioberry sudo[6819]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:42 volumioberry volumio[6091]: info: Shairport-Sync Started Jul 12 16:34:42 volumioberry sudo[6812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 16:34:42 volumioberry sudo[6812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 16:34:42 volumioberry sudo[6812]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreStateMachine::getcurrentVolume Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 16:34:43 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:43 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreStateMachine::updateTrackBlock Jul 12 16:34:43 volumioberry volumio[6091]: info: CorePlayQueue::getTrackBlock Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 16:34:43 volumioberry mpd[6825]: Jul 12 16:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 12 16:34:43 volumioberry systemd[1]: Started Music Player Daemon. Jul 12 16:34:43 volumioberry sudo[6756]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:43 volumioberry sudo[6765]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:43 volumioberry sudo[6806]: pam_unix(sudo:session): session closed for user root Jul 12 16:34:43 volumioberry volumio[6091]: error: MPD error: The expression evaluated to a falsy value: Jul 12 16:34:43 volumioberry volumio[6091]: assert.ok(self.idling) Jul 12 16:34:43 volumioberry volumio[6091]: error: The expression evaluated to a falsy value: Jul 12 16:34:43 volumioberry volumio[6091]: assert.ok(self.idling) Jul 12 16:34:43 volumioberry volumio[6091]: error: MPD error: The expression evaluated to a falsy value: Jul 12 16:34:43 volumioberry volumio[6091]: assert.ok(self.idling) Jul 12 16:34:43 volumioberry volumio[6091]: error: The expression evaluated to a falsy value: Jul 12 16:34:43 volumioberry volumio[6091]: assert.ok(self.idling) Jul 12 16:34:43 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:43 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:43 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:43 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreStateMachine::getcurrentVolume Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 16:34:45 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:45 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreStateMachine::updateTrackBlock Jul 12 16:34:45 volumioberry volumio[6091]: info: CorePlayQueue::getTrackBlock Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 16:34:45 volumioberry volumio[6091]: info: VolumeController:: Volume=86 Mute =false Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:45 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:45 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:51 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:51 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:53 volumioberry volumio[6091]: error: Upnp client error: Error: This socket has been ended by the other party Jul 12 16:34:55 volumioberry volumio[6091]: info: Clearing queue after UPNP request Jul 12 16:34:55 volumioberry volumio[6091]: info: CoreStateMachine::ClearQueue Jul 12 16:34:55 volumioberry volumio[6091]: info: CoreStateMachine::stop Jul 12 16:34:55 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 16:34:55 volumioberry volumio[6091]: info: CorePlayQueue::clearPlayQueue Jul 12 16:34:55 volumioberry volumio[6091]: info: CorePlayQueue::saveQueue Jul 12 16:34:55 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:55 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushQueue Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:56 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:56 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 8ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Starting UPNP Playback Jul 12 16:34:56 volumioberry volumio[6091]: info: Preparing playback through UPNP Jul 12 16:34:56 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:56 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:56 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: Jul 12 16:34:56 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:56 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:56 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:57 volumioberry volumio[6091]: info: Jul 12 16:34:57 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:34:57 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:34:57 volumioberry volumio[6091]: info: Jul 12 16:34:57 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:34:57 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:34:57 volumioberry volumio[6091]: info: Jul 12 16:34:57 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:34:57 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:34:57 volumioberry volumio[6091]: info: sendMpdCommand status took 7 milliseconds Jul 12 16:34:57 volumioberry volumio[6091]: info: sendMpdCommand status took 5 milliseconds Jul 12 16:34:57 volumioberry volumio[6091]: info: sendMpdCommand status took 3 milliseconds Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:34:57 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:34:57 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 16:34:57 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 12 16:34:58 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 12 16:34:58 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:34:58 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:34:58 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:34:58 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:58 volumioberry volumio[6091]: 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":"Army Of Me","artist":"Bjork","album":"Post","uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816493","trackType":""} Jul 12 16:34:58 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: No code Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:58 volumioberry volumio[6091]: 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":"Army Of Me","artist":"Bjork","album":"Post","uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816493","trackType":""} Jul 12 16:34:58 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: No code Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:58 volumioberry volumio[6091]: 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":"Army Of Me","artist":"Bjork","album":"Post","uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816493","trackType":""} Jul 12 16:34:58 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: No code Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 132ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 129ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 128ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Clearing queue after UPNP request Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::ClearQueue Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::stop Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::clearPlayQueue Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::saveQueue Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushQueue Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:58 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:58 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Starting UPNP Playback Jul 12 16:34:58 volumioberry volumio[6091]: info: Preparing playback through UPNP Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:34:58 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:34:58 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: Jul 12 16:34:58 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:34:58 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:34:58 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:35:01 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:35:01 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:35:01 volumioberry volumio[6091]: info: Listing playlists Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 48 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:04 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 52 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 50 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus stop Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 73ms Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:04 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:04 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:04 volumioberry volumio[6091]: info: Jul 12 16:35:04 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 29ms Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 27 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 13ms Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 10 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 8ms Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand status took 7 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:04 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:04 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:04 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:04 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 107ms Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 104ms Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:04 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"739 Kbps","isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:04 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:04 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:04 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"739 Kbps","isStreaming":false,"title":"925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/925ec9f3e1074dfd868fa21c9a70a6e6.flac?ts=1720816497","trackType":""} Jul 12 16:35:04 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:04 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:04 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:04 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:04 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:04 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:04 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 74ms Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 55ms Jul 12 16:35:04 volumioberry volumio[6091]: info: ------------------------------ 50ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand status took 6 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand status took 5 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand status took 3 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:06 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:06 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 37ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 35ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 33ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Clearing queue after UPNP request Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::ClearQueue Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::stop Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::stPlaybackTimer Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::updateTrackBlock Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrackBlock Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::serviceStop Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: info: ControllerMpd::stop Jul 12 16:35:06 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::clearPlayQueue Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::saveQueue Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushState Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::volumioPushQueue Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: sendMpdCommand stop took 32 milliseconds Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:35:06 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:35:06 volumioberry volumio[6091]: error: updateQueue error: null Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 9ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Starting UPNP Playback Jul 12 16:35:06 volumioberry volumio[6091]: info: Preparing playback through UPNP Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:35:06 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:06 volumioberry volumio[6091]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 6ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 3ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: Jul 12 16:35:06 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:06 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 7ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 5ms Jul 12 16:35:06 volumioberry volumio[6091]: info: ------------------------------ 4ms Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:09 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 26 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 30 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 25 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 11ms Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 6 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus stop Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 67ms Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:09 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces system playlist update Jul 12 16:35:09 volumioberry volumio[6091]: info: Ignoring MPD Status Update Jul 12 16:35:09 volumioberry volumio[6091]: info: Jul 12 16:35:09 volumioberry volumio[6091]: ---------------------------- MPD announces state update: player Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::getState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand status Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 11ms Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 10 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 11ms Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand status took 9 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:09 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:09 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:09 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:09 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:09 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:09 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 94ms Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 91ms Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 71ms Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:09 volumioberry volumio[6091]: verbose: ControllerMpd::parseTrackInfo Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"752 Kbps","isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:09 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: ControllerMpd::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::servicePushState Jul 12 16:35:09 volumioberry volumio[6091]: verbose: In UPNP mode Jul 12 16:35:09 volumioberry volumio[6091]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"739 Kbps","isStreaming":false,"title":"f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","artist":"Music Assistant","album":null,"uri":"http://192.168.1.213:8097/single/uuid:901e81e4-b920-99b9-f36d-2ccf67316a37/f44e0690eb3e43f5a08620a47db85142.flac?ts=1720816505","trackType":""} Jul 12 16:35:09 volumioberry volumio[6091]: verbose: CURRENT POSITION 0 Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState stateService play Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::syncState currentStatus play Jul 12 16:35:09 volumioberry volumio[6091]: info: Received an update from plugin. extracting info from payload Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 12 16:35:09 volumioberry volumio[6091]: info: CoreStateMachine::pushState Jul 12 16:35:09 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:09 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:09 volumioberry volumio[6091]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 54ms Jul 12 16:35:09 volumioberry volumio[6091]: info: ------------------------------ 52ms Jul 12 16:35:11 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:35:11 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:11 volumioberry volumio[6091]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:11 volumioberry volumio[6091]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 12 16:35:11 volumioberry volumio[6091]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Jul 12 16:35:11 volumioberry volumio[6091]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jul 12 16:35:11 volumioberry volumio[6091]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 12 16:35:11 volumioberry volumio[6091]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jul 12 16:35:11 volumioberry volumio[6091]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jul 12 16:35:11 volumioberry volumio[6091]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 12 16:35:11 volumioberry volumio[6091]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jul 12 16:35:11 volumioberry volumio[6091]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jul 12 16:35:11 volumioberry volumio[6091]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jul 12 16:35:11 volumioberry volumio[6091]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jul 12 16:35:11 volumioberry volumio[6091]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jul 12 16:35:11 volumioberry volumio[6091]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jul 12 16:35:11 volumioberry volumio[6091]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jul 12 16:35:11 volumioberry volumio[6091]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jul 12 16:35:11 volumioberry volumio[6091]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jul 12 16:35:21 volumioberry volumio[6091]: info: CoreCommandRouter::volumioGetState Jul 12 16:35:21 volumioberry volumio[6091]: info: CorePlayQueue::getTrack 0 Jul 12 16:35:21 volumioberry volumio[6091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 16:35:21 volumioberry volumio[6091]: TypeError: Cannot read property 'name' of undefined Jul 12 16:35:21 volumioberry volumio[6091]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jul 12 16:35:21 volumioberry volumio[6091]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Jul 12 16:35:21 volumioberry volumio[6091]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:55:38) Jul 12 16:35:21 volumioberry volumio[6091]: at Socket.emit (events.js:315:20) Jul 12 16:35:21 volumioberry volumio[6091]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 12 16:35:21 volumioberry volumio[6091]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 12 16:35:21 volumioberry volumio[6091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 16:35:21 volumioberry sudo[6909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-12 16:34 Jul 12 16:35:21 volumioberry sudo[6909]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"