-- Logs begin at Wed 2025-02-26 11:53:37 EST, end at Thu 2025-02-27 07:36:56 EST. -- Feb 27 07:35:04 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:04 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:07 volumiopi4a volumio[861]: error: Upnp client error: Error: This socket has been ended by the other party Feb 27 07:35:09 volumiopi4a volumio[861]: info: Clearing queue after UPNP request Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreStateMachine::ClearQueue Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreStateMachine::stop Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 07:35:09 volumiopi4a volumio[861]: info: CorePlayQueue::clearPlayQueue Feb 27 07:35:09 volumiopi4a volumio[861]: info: CorePlayQueue::saveQueue Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:09 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:09 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushQueue Feb 27 07:35:09 volumiopi4a volumio[861]: info: Feb 27 07:35:09 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:09 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:09 volumiopi4a volumio[861]: info: Feb 27 07:35:09 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:09 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:09 volumiopi4a volumio[861]: error: updateQueue error: null Feb 27 07:35:09 volumiopi4a volumio[861]: error: updateQueue error: null Feb 27 07:35:09 volumiopi4a volumio[861]: info: ------------------------------ 4ms Feb 27 07:35:09 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:09 volumiopi4a volumio[861]: info: Starting UPNP Playback Feb 27 07:35:09 volumiopi4a volumio[861]: info: Preparing playback through UPNP Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:09 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:09 volumiopi4a volumio[861]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:35:10 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand status took 4 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand status took 8 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand clearerror took 4 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"pause","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":"Mária Rádió Szerbia","artist":null,"album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/f9037bb30c0841909fd51d90753af03b.flac","trackType":"flac"} Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService pause Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus stop Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 19ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: Feb 27 07:35:10 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand status took 17 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand clearerror took 15 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand status took 5 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"pause","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":"Mária Rádió Szerbia","artist":null,"album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/f9037bb30c0841909fd51d90753af03b.flac","trackType":"flac"} Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService pause Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus stop Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 28ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"pause","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":"Mária Rádió Szerbia","artist":null,"album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/f9037bb30c0841909fd51d90753af03b.flac","trackType":"flac"} Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService pause Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus stop Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:10 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"pause","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":"Mária Rádió Szerbia","artist":null,"album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/f9037bb30c0841909fd51d90753af03b.flac","trackType":"flac"} Feb 27 07:35:10 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService pause Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus stop Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:10 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 30ms Feb 27 07:35:10 volumiopi4a volumio[861]: info: ------------------------------ 18ms Feb 27 07:35:14 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:14 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:35:14 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:35:17 volumiopi4a volumio[861]: error: error Feb 27 07:35:17 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:18 volumiopi4a volumio[861]: error: error Feb 27 07:35:24 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:28 volumiopi4a kernel: usb 1-1.2: new full-speed USB device number 38 using xhci_hcd Feb 27 07:35:28 volumiopi4a kernel: usb 1-1.2: device descriptor read/64, error -32 Feb 27 07:35:34 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:34 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:35:34 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:35:36 volumiopi4a kernel: usb 1-1.2: new high-speed USB device number 39 using xhci_hcd Feb 27 07:35:36 volumiopi4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.01 Feb 27 07:35:37 volumiopi4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 27 07:35:37 volumiopi4a kernel: usb 1-1.2: Product: SMSL USB AUDIO Feb 27 07:35:37 volumiopi4a kernel: usb 1-1.2: Manufacturer: SMSL Feb 27 07:35:37 volumiopi4a kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Feb 27 07:35:37 volumiopi4a kernel: hid-generic 0003:152A:85DD.000F: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.2/input3 Feb 27 07:35:37 volumiopi4a volumio[861]: info: Feb 27 07:35:37 volumiopi4a volumio[861]: ---------------------------- USB Audio Device Attached Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::Close All Modals sent Feb 27 07:35:37 volumiopi4a volumio[861]: info: Preparing to save Alsa Options, stopping services first Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioStop Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreStateMachine::stop Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 07:35:37 volumiopi4a volumio[861]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: Device 5 does not have any Mixer Control Available Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 27 07:35:37 volumiopi4a volumio[861]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 27 07:35:37 volumiopi4a volumio[861]: info: Disabling external Volume Control Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:37 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:37 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:37 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:37 volumiopi4a volumio[861]: info: Preparing to generate the ALSA configuration file Feb 27 07:35:37 volumiopi4a volumio[861]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 07:35:37 volumiopi4a volumio[861]: info: Reading ALSA contributions from plugins. Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 27 07:35:37 volumiopi4a volumio[861]: info: Asound.conf file unchanged, so no further update is needed Feb 27 07:35:37 volumiopi4a volumio[861]: info: Output device has changed, restarting MPD Feb 27 07:35:37 volumiopi4a sudo[14327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 07:35:37 volumiopi4a sudo[14327]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:37 volumiopi4a sudo[14327]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:37 volumiopi4a volumio[861]: info: Output device has changed, restarting Shairport Sync Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:35:37 volumiopi4a sudo[14330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 07:35:37 volumiopi4a sudo[14330]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:37 volumiopi4a systemd[1]: Stopping Music Player Daemon... Feb 27 07:35:37 volumiopi4a systemd[1]: mpd.service: Succeeded. Feb 27 07:35:37 volumiopi4a systemd[1]: Stopped Music Player Daemon. Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:37 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 07:35:37 volumiopi4a volumio[861]: info: MPD Permissions set Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a systemd[1]: Starting Music Player Daemon... Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:37 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:37 volumiopi4a volumio[861]: info: Starting Shairport Sync Feb 27 07:35:37 volumiopi4a volumio[861]: info: Preparing to generate the ALSA configuration file Feb 27 07:35:37 volumiopi4a sudo[14343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 07:35:37 volumiopi4a sudo[14343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:37 volumiopi4a volumio[861]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 27 07:35:37 volumiopi4a volumio[861]: info: Reading ALSA contributions from plugins. Feb 27 07:35:37 volumiopi4a volumio[861]: info: Asound.conf file written Feb 27 07:35:37 volumiopi4a sudo[14335]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 07:35:37 volumiopi4a sudo[14335]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:37 volumiopi4a sudo[14335]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:37 volumiopi4a systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 07:35:37 volumiopi4a systemd[1]: shairport-sync.service: Succeeded. Feb 27 07:35:37 volumiopi4a systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 07:35:37 volumiopi4a systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 07:35:37 volumiopi4a sudo[14348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 27 07:35:37 volumiopi4a sudo[14343]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:37 volumiopi4a sudo[14348]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:37 volumiopi4a sudo[14348]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:37 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:37 volumiopi4a volumio[861]: info: Output device has changed, restarting MPD Feb 27 07:35:38 volumiopi4a sudo[14356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 27 07:35:38 volumiopi4a sudo[14356]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:38 volumiopi4a sudo[14356]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:38 volumiopi4a volumio[861]: info: Output device has changed, restarting Shairport Sync Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:35:38 volumiopi4a sudo[14359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 27 07:35:38 volumiopi4a sudo[14359]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:38 volumiopi4a systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 27 07:35:38 volumiopi4a systemd[1]: mpd.service: Succeeded. Feb 27 07:35:38 volumiopi4a systemd[1]: Stopped Music Player Daemon. Feb 27 07:35:38 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:38 volumiopi4a volumio[861]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 27 07:35:38 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:35:38 volumiopi4a volumio[861]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:38 volumiopi4a systemd[1]: Starting Music Player Daemon... Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 07:35:38 volumiopi4a volumio[861]: info: MPD Permissions set Feb 27 07:35:38 volumiopi4a volumio[861]: info: Shairport-Sync Started Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 07:35:38 volumiopi4a volumio[861]: info: Starting Shairport Sync Feb 27 07:35:38 volumiopi4a sudo[14364]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 07:35:38 volumiopi4a sudo[14364]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:38 volumiopi4a sudo[14364]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:38 volumiopi4a sudo[14373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 07:35:38 volumiopi4a sudo[14373]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:35:38 volumiopi4a systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 07:35:38 volumiopi4a systemd[1]: shairport-sync.service: Succeeded. Feb 27 07:35:38 volumiopi4a systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 07:35:38 volumiopi4a volumio[861]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 07:35:38 volumiopi4a systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 07:35:38 volumiopi4a sudo[14373]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:38 volumiopi4a volumio[861]: info: Shairport-Sync Started Feb 27 07:35:38 volumiopi4a mpd[14375]: Feb 27 07:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 27 07:35:38 volumiopi4a systemd[1]: Started Music Player Daemon. Feb 27 07:35:38 volumiopi4a sudo[14330]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:38 volumiopi4a sudo[14359]: pam_unix(sudo:session): session closed for user root Feb 27 07:35:38 volumiopi4a volumio[861]: error: MPD error: The expression evaluated to a falsy value: Feb 27 07:35:38 volumiopi4a volumio[861]: assert.ok(self.idling) Feb 27 07:35:38 volumiopi4a volumio[861]: error: The expression evaluated to a falsy value: Feb 27 07:35:38 volumiopi4a volumio[861]: assert.ok(self.idling) Feb 27 07:35:38 volumiopi4a volumio[861]: error: updateQueue error: null Feb 27 07:35:41 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 27 07:35:41 volumiopi4a volumio[861]: info: CoreStateMachine::getcurrentVolume Feb 27 07:35:41 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioRetrievevolume Feb 27 07:35:41 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:35:41 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:41 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:35:41 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:35:41 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:35:44 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:44 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:54 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:35:54 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:35:54 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:35:54 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:02 volumiopi4a volumio[861]: error: Upnp client error: Error: This socket has been ended by the other party Feb 27 07:36:04 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:04 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:08 volumiopi4a volumio[861]: error: error Feb 27 07:36:14 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:14 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:14 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:14 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:24 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:24 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:33 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:33 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 07:36:33 volumiopi4a volumio[861]: verbose: New Socket.io Connection to 192.168.4.108:3000 from 192.168.4.167 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:33 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 27 07:36:33 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 27 07:36:34 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:34 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:34 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:34 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:35 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:35 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:35 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:35 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:35 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:35 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 07:36:36 volumiopi4a sudo[14594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 07:36:36 volumiopi4a sudo[14594]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:36:36 volumiopi4a sudo[14594]: pam_unix(sudo:session): session closed for user root Feb 27 07:36:36 volumiopi4a sudo[14597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 07:36:36 volumiopi4a sudo[14597]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:36:36 volumiopi4a sudo[14597]: pam_unix(sudo:session): session closed for user root Feb 27 07:36:36 volumiopi4a volumio[861]: verbose: New Socket.io Connection to 192.168.4.108 from 192.168.4.167 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Feb 27 07:36:36 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 07:36:36 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 07:36:36 volumiopi4a sudo[14600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 07:36:36 volumiopi4a sudo[14600]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:36:36 volumiopi4a sudo[14600]: pam_unix(sudo:session): session closed for user root Feb 27 07:36:36 volumiopi4a sudo[14603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 07:36:36 volumiopi4a sudo[14603]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 07:36:36 volumiopi4a sudo[14603]: pam_unix(sudo:session): session closed for user root Feb 27 07:36:37 volumiopi4a volumio[861]: verbose: New Socket.io Connection to 192.168.4.108 from 192.168.4.167 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:37 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 07:36:37 volumiopi4a volumio[861]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 27 07:36:37 volumiopi4a volumio[861]: info: Received Get System Info Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:37 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:37 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:37 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:37 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 07:36:37 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:38 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 07:36:38 volumiopi4a volumio[861]: info: Received Get System Info Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:38 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:38 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:38 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 27 07:36:39 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 07:36:39 volumiopi4a volumio[861]: xcb_connection_has_error() returned true Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 07:36:39 volumiopi4a volumio[861]: info: Received Get System Info Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:39 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:39 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:39 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 07:36:44 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:44 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:45 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:45 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:47 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 07:36:52 volumiopi4a volumio[861]: info: Clearing queue after UPNP request Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreStateMachine::ClearQueue Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreStateMachine::stop Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 27 07:36:52 volumiopi4a volumio[861]: info: CorePlayQueue::clearPlayQueue Feb 27 07:36:52 volumiopi4a volumio[861]: info: CorePlayQueue::saveQueue Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushState Feb 27 07:36:52 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output update for this device Feb 27 07:36:52 volumiopi4a volumio[861]: info: MRS: Pushing multiroomSync output Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioPushQueue Feb 27 07:36:52 volumiopi4a volumio[861]: info: Feb 27 07:36:52 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:52 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:52 volumiopi4a volumio[861]: info: Feb 27 07:36:52 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:52 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:52 volumiopi4a volumio[861]: error: updateQueue error: null Feb 27 07:36:52 volumiopi4a volumio[861]: error: updateQueue error: null Feb 27 07:36:52 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:52 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:52 volumiopi4a volumio[861]: info: Starting UPNP Playback Feb 27 07:36:52 volumiopi4a volumio[861]: info: Preparing playback through UPNP Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:52 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:52 volumiopi4a volumio[861]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand status took 27 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand status took 27 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 2ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand status took 1 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:36:53 volumiopi4a volumio[861]: 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":"3a1153254f784b9199f21a180d25cb31.flac","artist":"Radio Maestro","album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/3a1153254f784b9199f21a180d25cb31.flac","trackType":"flac"} Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService play Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus stop Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 30ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces system playlist update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Ignoring MPD Status Update Feb 27 07:36:53 volumiopi4a volumio[861]: info: Feb 27 07:36:53 volumiopi4a volumio[861]: ---------------------------- MPD announces state update: player Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::getState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand status Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 1ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand status took 2 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"232 Kbps","isStreaming":false,"title":"3a1153254f784b9199f21a180d25cb31.flac","artist":"Radio Maestro","album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/3a1153254f784b9199f21a180d25cb31.flac","trackType":"flac"} Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService play Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus play Feb 27 07:36:53 volumiopi4a volumio[861]: info: Received an update from plugin. extracting info from payload Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"229 Kbps","isStreaming":false,"title":"3a1153254f784b9199f21a180d25cb31.flac","artist":"Radio Maestro","album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/3a1153254f784b9199f21a180d25cb31.flac","trackType":"flac"} Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService play Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus play Feb 27 07:36:53 volumiopi4a volumio[861]: info: Received an update from plugin. extracting info from payload Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:53 volumiopi4a volumio[861]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 27 07:36:53 volumiopi4a volumio[861]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 34ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 8ms Feb 27 07:36:53 volumiopi4a volumio[861]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: ControllerMpd::parseTrackInfo Feb 27 07:36:53 volumiopi4a volumio[861]: info: ControllerMpd::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::servicePushState Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: In UPNP mode Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"242 Kbps","isStreaming":false,"title":"3a1153254f784b9199f21a180d25cb31.flac","artist":"Radio Maestro","album":null,"uri":"http://192.168.4.128:8098/single/uuid:834c3591-02f8-8528-07bc-e45f015ed24e/3a1153254f784b9199f21a180d25cb31.flac","trackType":"flac"} Feb 27 07:36:53 volumiopi4a volumio[861]: verbose: CURRENT POSITION 0 Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState stateService play Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::syncState currentStatus play Feb 27 07:36:53 volumiopi4a volumio[861]: info: Received an update from plugin. extracting info from payload Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 27 07:36:53 volumiopi4a volumio[861]: info: CoreStateMachine::pushState Feb 27 07:36:53 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:53 volumiopi4a volumio[861]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Feb 27 07:36:53 volumiopi4a volumio[861]: info: ------------------------------ 7ms Feb 27 07:36:54 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:54 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:54 volumiopi4a volumio[861]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Feb 27 07:36:54 volumiopi4a volumio[861]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 27 07:36:54 volumiopi4a volumio[861]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 27 07:36:54 volumiopi4a volumio[861]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Feb 27 07:36:54 volumiopi4a volumio[861]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 27 07:36:54 volumiopi4a volumio[861]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Feb 27 07:36:54 volumiopi4a volumio[861]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Feb 27 07:36:54 volumiopi4a volumio[861]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 27 07:36:54 volumiopi4a volumio[861]: at /volumio/node_modules/express/lib/router/index.js:281:22 Feb 27 07:36:54 volumiopi4a volumio[861]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 27 07:36:54 volumiopi4a volumio[861]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 27 07:36:54 volumiopi4a volumio[861]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Feb 27 07:36:54 volumiopi4a volumio[861]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Feb 27 07:36:54 volumiopi4a volumio[861]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Feb 27 07:36:54 volumiopi4a volumio[861]: at /volumio/node_modules/express/lib/router/index.js:284:7 Feb 27 07:36:54 volumiopi4a volumio[861]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Feb 27 07:36:54 volumiopi4a volumio[861]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Feb 27 07:36:54 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:54 volumiopi4a volumio[861]: info: Listing playlists Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 07:36:55 volumiopi4a volumio[861]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 27 07:36:55 volumiopi4a volumio[861]: info: Received Get System Version Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 07:36:55 volumiopi4a volumio[861]: info: Received Get System Info Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 07:36:55 volumiopi4a volumio[861]: info: Discovery: Getting this device information Feb 27 07:36:55 volumiopi4a volumio[861]: info: CoreCommandRouter::volumioGetState Feb 27 07:36:55 volumiopi4a volumio[861]: info: CorePlayQueue::getTrack 0 Feb 27 07:36:55 volumiopi4a volumio[861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 07:36:55 volumiopi4a volumio[861]: TypeError: Cannot read property 'name' of undefined Feb 27 07:36:55 volumiopi4a volumio[861]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Feb 27 07:36:55 volumiopi4a volumio[861]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Feb 27 07:36:55 volumiopi4a volumio[861]: at ControllerVolumioDiscovery.getThisDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:464:38) Feb 27 07:36:55 volumiopi4a volumio[861]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 27 07:36:55 volumiopi4a volumio[861]: at ControllerSystem.getSystemInfo (/volumio/app/plugins/system_controller/system/index.js:617:45) Feb 27 07:36:55 volumiopi4a volumio[861]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 27 07:36:55 volumiopi4a volumio[861]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1051:49) Feb 27 07:36:55 volumiopi4a volumio[861]: at Socket.emit (events.js:315:20) Feb 27 07:36:55 volumiopi4a volumio[861]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 27 07:36:55 volumiopi4a volumio[861]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 27 07:36:55 volumiopi4a volumio[861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 07:36:56 volumiopi4a sudo[14684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 07:35 Feb 27 07:36:56 volumiopi4a sudo[14684]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"