-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Tue 2024-08-27 14:32:36 UTC. -- Aug 27 14:31:10 volumiosamadhy-tinker ntpd[1093]: Soliciting pool server 2603:c022:8004:7800:65bf:c600:a739:1bee Aug 27 14:31:41 volumiosamadhy-tinker kernel: usb 1-1: reset high-speed USB device number 14 using dwc2 Aug 27 14:31:41 volumiosamadhy-tinker kernel: usb 1-1: USB disconnect, device number 14 Aug 27 14:31:41 volumiosamadhy-tinker kernel: usb 1-1: new high-speed USB device number 15 using dwc2 Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1: Product: USB2.0 Hub Aug 27 14:31:42 volumiosamadhy-tinker kernel: hub 1-1:1.0: USB hub found Aug 27 14:31:42 volumiosamadhy-tinker kernel: hub 1-1:1.0: 4 ports detected Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1.2: new high-speed USB device number 16 using dwc2 Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=86ef Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1.2: Product: USB HiRes Audio Aug 27 14:31:42 volumiosamadhy-tinker kernel: usb 1-1.2: Manufacturer: DENAFRIPS Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: ---------------------------- USB Audio Device Attached Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::Close All Modals sent Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Preparing to save Alsa Options, stopping services first Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPause Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pause Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB HiRes Audio","alsacard":"Audio"},"i2s":false} Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Setting mixer PCM for card USB HiRes Audio Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Updating Volume Controller Parameters: Device: 5 Name: USB HiRes Audio Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Disabling external Volume Control Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Preparing to generate the ALSA configuration file Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Asound.conf file unchanged, so no further update is needed Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting MPD Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting Shairport Sync Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:31:42 volumiosamadhy-tinker sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:42 volumiosamadhy-tinker sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:31:42 volumiosamadhy-tinker sudo[2095]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:42 volumiosamadhy-tinker sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: musicservicesshield.service: Succeeded. Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: MPD Permissions set Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopping Music Player Daemon... Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Starting Shairport Sync Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Preparing to generate the ALSA configuration file Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: mpd.service: Succeeded. Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopped Music Player Daemon. Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Starting Music Player Daemon... Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Asound.conf file unchanged, so no further update is needed Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting MPD Aug 27 14:31:42 volumiosamadhy-tinker sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 14:31:42 volumiosamadhy-tinker sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting Shairport Sync Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:42 volumiosamadhy-tinker sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: shairport-sync.service: Succeeded. Aug 27 14:31:42 volumiosamadhy-tinker sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 14:31:42 volumiosamadhy-tinker sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:31:42 volumiosamadhy-tinker sudo[2132]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:42 volumiosamadhy-tinker sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:42 volumiosamadhy-tinker systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 14:31:42 volumiosamadhy-tinker sudo[2125]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:31:42 volumiosamadhy-tinker sudo[2124]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:43 volumiosamadhy-tinker sudo[2125]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker sudo[2125]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: MPD Permissions set Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: Shairport-Sync Started Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: Starting Shairport Sync Aug 27 14:31:43 volumiosamadhy-tinker sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: mpd.service: Succeeded. Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: Stopped Music Player Daemon. Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: Starting Music Player Daemon... Aug 27 14:31:43 volumiosamadhy-tinker sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: shairport-sync.service: Succeeded. Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 14:31:43 volumiosamadhy-tinker systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 14:31:43 volumiosamadhy-tinker sudo[2175]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:43 volumiosamadhy-tinker volumio[1108]: info: Shairport-Sync Started Aug 27 14:31:43 volumiosamadhy-tinker sudo[2179]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:31:43 volumiosamadhy-tinker sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:31:43 volumiosamadhy-tinker sudo[2179]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:44 volumiosamadhy-tinker mpd[2196]: Aug 27 14:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 14:31:44 volumiosamadhy-tinker systemd[1]: Started Music Player Daemon. Aug 27 14:31:44 volumiosamadhy-tinker sudo[2098]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:44 volumiosamadhy-tinker sudo[2134]: pam_unix(sudo:session): session closed for user root Aug 27 14:31:44 volumiosamadhy-tinker systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 27 14:31:44 volumiosamadhy-tinker volumio[1108]: error: MPD error: The expression evaluated to a falsy value: Aug 27 14:31:44 volumiosamadhy-tinker volumio[1108]: assert.ok(self.idling) Aug 27 14:31:44 volumiosamadhy-tinker volumio[1108]: error: The expression evaluated to a falsy value: Aug 27 14:31:44 volumiosamadhy-tinker volumio[1108]: assert.ok(self.idling) Aug 27 14:31:44 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:31:44 volumiosamadhy-tinker volumio[2202]: cset: --> shielding system active with Aug 27 14:31:44 volumiosamadhy-tinker volumio[2202]: cset: "system" cpuset of CPUSPEC(0-1) with 118 tasks running Aug 27 14:31:44 volumiosamadhy-tinker volumio[2202]: cset: "user" cpuset of CPUSPEC(2-3) with 3 tasks running Aug 27 14:31:44 volumiosamadhy-tinker systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: verbose: New Socket.io Connection to 192.168.219.44 from 192.168.219.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: Received Get System Info Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: Discovery: Getting this device information Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: Listing playlists Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::getcurrentVolume Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::updateTrackBlock Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrackBlock Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: Discovery: Getting this device information Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 0 Aug 27 14:31:46 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:48 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetQueue Aug 27 14:31:48 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::getQueue Aug 27 14:31:48 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getQueue Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPlay Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::play index 6 Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stop Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::play index undefined Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::startPlaybackTimer Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: [1724769109594] ControllerQobuz::clearAddPlayTrack Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand stop took 2 milliseconds Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand clear took 2 milliseconds Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/94414325" Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 4ms Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Handling url /?data=qobuz://song/94414325 Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: Executing endpoint getStreamUrlqobuz Aug 27 14:31:49 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 14:31:50 volumiosamadhy-tinker volumio[1108]: info: getStreamUrl took 1090 milliseconds Aug 27 14:31:50 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1677507&eid=94414325&fmt=7&profile=raw&app_id=539451548&cid=1474666&etsp=1724772710&hmac=gG2Yneavx_aHXYQUAfhE2dHCddA Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Response: 200, length: 73400889 Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/94414325" Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Client dropped request, destroying Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 3145ms Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/94414325" took 2 milliseconds Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand play Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Handling url /?data=qobuz://song/94414325 Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 7ms Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand play took 6 milliseconds Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 6ms Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: Executing endpoint getStreamUrlqobuz Aug 27 14:31:52 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: getStreamUrl took 929 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1677507&eid=94414325&fmt=7&profile=raw&app_id=539451548&cid=1474666&etsp=1724772713&hmac=tvb_0uWlGTs9E6EE5oI6mR4Ku0s Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Response: 200, length: 73400889 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 11 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 10 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 4 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Received an update from plugin. extracting info from payload Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 44ms Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 51ms Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 36 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 2 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Received an update from plugin. extracting info from payload Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 69ms Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 44 milliseconds Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: Received an update from plugin. extracting info from payload Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:31:53 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 75ms Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 14:31:56 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 14:32:09 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: Preparing to save Alsa Options, stopping services first Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stPlaybackTimer Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::servicePause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: [1724769133847] ControllerQobuz::pause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand pause Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB HiRes Audio"}} Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:32:13 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Setting mixer PCM for card USB HiRes Audio Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Updating Volume Controller Parameters: Device: 5 Name: USB HiRes Audio Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Disabling external Volume Control Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Preparing to generate the ALSA configuration file Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand pause took 293 milliseconds Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 20 milliseconds Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 19 milliseconds Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20989,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2521 Kbps","isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService pause Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus pause Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stPlaybackTimer Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20989,"duration":232,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2521 Kbps","isStreaming":false,"title":"94414325","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/94414325","trackType":"qobuz"} Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 6 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService pause Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus pause Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stPlaybackTimer Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 58ms Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 56ms Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Asound.conf file unchanged, so no further update is needed Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting MPD Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting Shairport Sync Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker sudo[2257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:32:14 volumiosamadhy-tinker sudo[2260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:32:14 volumiosamadhy-tinker sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:14 volumiosamadhy-tinker sudo[2257]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 14:32:14 volumiosamadhy-tinker sudo[2260]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: MPD Permissions set Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: musicservicesshield.service: Succeeded. Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopping Music Player Daemon... Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Client dropped request, destroying Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: mpd.service: Succeeded. Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopped Music Player Daemon. Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Starting Music Player Daemon... Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Starting Shairport Sync Aug 27 14:32:14 volumiosamadhy-tinker sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 14:32:14 volumiosamadhy-tinker sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:14 volumiosamadhy-tinker sudo[2282]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:32:14 volumiosamadhy-tinker sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: shairport-sync.service: Succeeded. Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 14:32:14 volumiosamadhy-tinker sudo[2282]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:14 volumiosamadhy-tinker systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 14:32:14 volumiosamadhy-tinker sudo[2286]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:14 volumiosamadhy-tinker volumio[1108]: info: Shairport-Sync Started Aug 27 14:32:15 volumiosamadhy-tinker mpd[2306]: Aug 27 14:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 14:32:15 volumiosamadhy-tinker systemd[1]: Started Music Player Daemon. Aug 27 14:32:15 volumiosamadhy-tinker sudo[2260]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:15 volumiosamadhy-tinker systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 27 14:32:15 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:32:15 volumiosamadhy-tinker volumio[2310]: cset: --> shielding system active with Aug 27 14:32:15 volumiosamadhy-tinker volumio[2310]: cset: "system" cpuset of CPUSPEC(0-1) with 120 tasks running Aug 27 14:32:15 volumiosamadhy-tinker volumio[2310]: cset: "user" cpuset of CPUSPEC(2-3) with 3 tasks running Aug 27 14:32:15 volumiosamadhy-tinker systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::getcurrentVolume Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::updateTrackBlock Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrackBlock Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: VolumeController:: Volume=100 Mute =false Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:18 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:19 volumiosamadhy-tinker ntpd[1093]: Soliciting pool server 2401:c080:1c00:24a1:5400:5ff:fe04:720 Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetState Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Restoring Previous Volume level: 100 false false Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting MPD Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Output device has changed, restarting Shairport Sync Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:32:20 volumiosamadhy-tinker sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:20 volumiosamadhy-tinker sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:32:20 volumiosamadhy-tinker sudo[2333]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:20 volumiosamadhy-tinker sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Volume configurations have been set Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: musicservicesshield.service: Succeeded. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopping Music Player Daemon... Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Updating Volume Controller Parameters: Device: 5 Name: USB HiRes Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Disabling external Volume Control Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: mpd.service: Succeeded. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopped Music Player Daemon. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Starting Music Player Daemon... Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: MPD Permissions set Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker sudo[2357]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 14:32:20 volumiosamadhy-tinker sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 14:32:20 volumiosamadhy-tinker sudo[2357]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Starting Shairport Sync Aug 27 14:32:20 volumiosamadhy-tinker sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 14:32:20 volumiosamadhy-tinker sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: shairport-sync.service: Succeeded. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 27 14:32:20 volumiosamadhy-tinker systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 14:32:20 volumiosamadhy-tinker sudo[2377]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:20 volumiosamadhy-tinker volumio[1108]: info: Shairport-Sync Started Aug 27 14:32:21 volumiosamadhy-tinker mpd[2380]: Aug 27 14:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 14:32:21 volumiosamadhy-tinker systemd[1]: Started Music Player Daemon. Aug 27 14:32:21 volumiosamadhy-tinker sudo[2336]: pam_unix(sudo:session): session closed for user root Aug 27 14:32:21 volumiosamadhy-tinker systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 27 14:32:21 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:32:21 volumiosamadhy-tinker volumio[2393]: cset: --> shielding system active with Aug 27 14:32:21 volumiosamadhy-tinker volumio[2393]: cset: "system" cpuset of CPUSPEC(0-1) with 120 tasks running Aug 27 14:32:21 volumiosamadhy-tinker volumio[2393]: cset: "user" cpuset of CPUSPEC(2-3) with 3 tasks running Aug 27 14:32:21 volumiosamadhy-tinker systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPlay Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::play index 7 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::updateTrackBlock Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrackBlock Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::stPlaybackTimer Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::serviceStop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 6 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::serviceStop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: [1724769144328] ControllerQobuz::stop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::stop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand stop took 6 milliseconds Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::play index undefined Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 7 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::startPlaybackTimer Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 7 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: [1724769144339] ControllerQobuz::clearAddPlayTrack Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand stop took 0 milliseconds Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand clear took 4 milliseconds Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/98514546" Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: error: updateQueue error: null Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Handling url /?data=qobuz://song/98514546 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 6ms Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: Executing endpoint getStreamUrlqobuz Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: VolumeController::SetAlsaVolume100 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 7 Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:32:24 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:25 volumiosamadhy-tinker volumio[1108]: info: getStreamUrl took 1015 milliseconds Aug 27 14:32:25 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1677507&eid=98514546&fmt=7&profile=raw&app_id=539451548&cid=1474666&etsp=1724772745&hmac=gejMcoSDV8xnuHPDonl1AJfGZw8 Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Response: 200, length: 201603284 Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Client dropped request, destroying Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/98514546" Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces system playlist update Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: Ignoring MPD Status Update Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/98514546" took 2 milliseconds Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand play Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 6ms Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand play took 2 milliseconds Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Handling url /?data=qobuz://song/98514546 Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: Executing endpoint getStreamUrlqobuz Aug 27 14:32:26 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: getStreamUrl took 843 milliseconds Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1677507&eid=98514546&fmt=7&profile=raw&app_id=539451548&cid=1474666&etsp=1724772747&hmac=k39j9qbCSuB_d-U1-TMfVZG5llw Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: STREAMING PROXY: Response: 200, length: 201603284 Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: ---------------------------- MPD announces state update: player Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 10 milliseconds Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::getState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand status took 1 milliseconds Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 7 Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":556,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"98514546","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/98514546","trackType":"qobuz"} Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 7 Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 15ms Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ControllerMpd::pushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::servicePushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CorePlayQueue::getTrack 7 Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":556,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"98514546","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/98514546","trackType":"qobuz"} Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: verbose: CURRENT POSITION 7 Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState stateService play Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: Received an update from plugin. extracting info from payload Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreStateMachine::pushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioPushState Aug 27 14:32:27 volumiosamadhy-tinker volumio[1108]: info: ------------------------------ 18ms Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Retrieving Cloud Streaming UI Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Getting Tidal Cloud Configuration Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Getting Qobuz Cloud Configuration Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Asking plugin for UI Config Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Getting Spotify Cloud Configuration Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Asking plugin for UI Config Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Saving Spotify Acccount Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Got it Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Got Tidal Cloud Configuration Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: Got it Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 27 14:32:34 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: Disabling MyMusic plugin upnp Aug 27 14:32:36 volumiosamadhy-tinker sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 27 14:32:36 volumiosamadhy-tinker sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 14:32:36 volumiosamadhy-tinker systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: error: Upnp client error: Error: This socket has been ended by the other party Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: Enabling MyMusic plugin upnp Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: Enabling plugin upnp Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: Loading plugin "upnp"... Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: [1724769156562] Starting Upmpd Daemon Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: Error: listen EADDRINUSE: address already in use :::6599 Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at listenInCluster (net.js:1379:12) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at Server.listen (net.js:1465:7) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: code: 'EADDRINUSE', Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: errno: -98, Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: syscall: 'listen', Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: address: '::', Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: port: 6599 Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: } Aug 27 14:32:36 volumiosamadhy-tinker volumio[1108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 14:32:36 volumiosamadhy-tinker sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 14:31 Aug 27 14:32:36 volumiosamadhy-tinker sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:35:31 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="8e206762eb84baf3ce9616e2bda58682"