-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Thu 2025-04-17 06:58:14 +07. -- Apr 17 06:57:00 volumio volumio[902]: info: Successfully Added MyVolumio device Apr 17 06:57:00 volumio volumio[902]: info: Updating MyVolumio device info Apr 17 06:57:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:00 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:00 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 17 06:57:01 volumio volumio[902]: info: Successfully Updated MyVolumio device Apr 17 06:57:02 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 17 06:57:10 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 17 06:57:10 volumio volumio[902]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 17 06:57:10 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:10 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:10 volumio volumio[1308]: .......................................................................................................................................................................................................++++ Apr 17 06:57:12 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.10 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Apr 17 06:57:12 volumio volumio[902]: info: Received Get System Info Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:57:12 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetQueue Apr 17 06:57:12 volumio volumio[902]: info: CoreStateMachine::getQueue Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getQueue Apr 17 06:57:12 volumio volumio[902]: info: Listing playlists Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 06:57:12 volumio volumio[902]: info: Received Get System Info Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:57:12 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: VolumeController::SetAlsaVolume0 Apr 17 06:57:12 volumio volumio[902]: info: CoreStateMachine::pushState Apr 17 06:57:12 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Apr 17 06:57:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 17 06:57:12 volumio volumio[902]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 17 06:57:13 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 17 06:57:14 volumio volumio[1308]: ..................................++++ Apr 17 06:57:14 volumio volumio[1308]: e is 65537 (0x010001) Apr 17 06:57:14 volumio volumio[1308]: writing RSA key Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 06:57:15 volumio volumio-remote-updater[603]: No test mode Apr 17 06:57:15 volumio volumio-remote-updater[603]: No alpha test mode Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 06:57:15 volumio volumio[902]: info: CURURI: music-library Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 06:57:15 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 06:57:15 volumio volumio[902]: info: Preload queue cleared Apr 17 06:57:16 volumio volumio[902]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 17 06:57:16 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 17 06:57:16 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 06:57:16 volumio volumio[902]: info: CURURI: music-library/USB Apr 17 06:57:16 volumio volumio[902]: info: Preload queue cleared Apr 17 06:57:16 volumio volumio[902]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 17 06:57:16 volumio volumio[902]: info: Completed starting MyVolumio Plugin Apr 17 06:57:16 volumio volumio[902]: [Metrics] CommandRouter: 37s 188.14ms Apr 17 06:57:16 volumio volumio[902]: info: CoreCommandRouter::volumiosetStartupVolume Apr 17 06:57:16 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:16 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:17 volumio volumio[902]: info: CoreCommandRouter::Close All Modals sent Apr 17 06:57:17 volumio volumio[902]: info: CoreCommandRouter::Close All Modals sent Apr 17 06:57:17 volumio volumio[902]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 17 06:57:17 volumio volumio[902]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Apr 17 06:57:17 volumio volumio[902]: aplay: main:828: audio open error: No such device Apr 17 06:57:17 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 06:57:17 volumio volumio[902]: info: CURURI: music-library/USB/24DE5A86DE5A4FE2 Apr 17 06:57:17 volumio volumio[902]: info: Preload queue cleared Apr 17 06:57:18 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 17 06:57:18 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 06:57:18 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 17 06:57:18 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 06:57:22 volumio volumio[902]: Invalid card number. Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 17 06:57:22 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 17 06:57:24 volumio volumio[902]: info: BOOT COMPLETED Apr 17 06:57:29 volumio volumio[902]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 17 06:57:29 volumio volumio[902]: info: Preparing to save Alsa Options, stopping services first Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:29 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::volumioPause Apr 17 06:57:29 volumio volumio[902]: info: CoreStateMachine::pause Apr 17 06:57:29 volumio volumio[902]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB AUDIO DAC"},"i2s":true,"i2sid":{"value":"wisdpi-hifi-dac","label":"wisdPi Hifi DAC"}} Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: Enabling I2S DAC: wisdPi Hifi DAC Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 17 06:57:29 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:29 volumio volumio[902]: info: No Overlays Loaded Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Apr 17 06:57:29 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: Found match in i2s Card Database: setting mixer Digital for card wisdPi Hifi DAC Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 17 06:57:29 volumio volumio[902]: info: Updating Volume Controller Parameters: Device: 2 Name: wisdPi Hifi DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 17 06:57:29 volumio volumio[902]: info: Disabling external Volume Control Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 17 06:57:29 volumio volumio[902]: info: Preparing to generate the ALSA configuration file Apr 17 06:57:29 volumio volumio[902]: error: Cannot enable I2S Param: Error: Command failed: /usr/bin/sudo /usr/bin/dtoverlay hifiberry-dacplus Apr 17 06:57:29 volumio volumio[902]: * Failed to apply overlay '0_hifiberry-dacplus' (kernel) Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 17 06:57:29 volumio volumio[902]: info: Getting Alsa Cards List without I2S DAC Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 17 06:57:29 volumio volumio[902]: info: VolumeController:: Volume=94 Mute =false Apr 17 06:57:29 volumio volumio[902]: info: CoreStateMachine::pushState Apr 17 06:57:29 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Apr 17 06:57:29 volumio volumio[902]: info: Asound.conf file written Apr 17 06:57:29 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 17 06:57:29 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:29 volumio volumio[902]: info: Output device has changed, restarting MPD Apr 17 06:57:29 volumio sudo[1475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 06:57:29 volumio sudo[1475]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio volumio[902]: info: Output device has changed, restarting Shairport Sync Apr 17 06:57:29 volumio sudo[1475]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 06:57:29 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 06:57:29 volumio volumio[902]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 06:57:29 volumio volumio[902]: info: Output device has changed, restarting MPD Apr 17 06:57:29 volumio systemd[1]: mpd.service: Succeeded. Apr 17 06:57:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 06:57:29 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 06:57:29 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio systemd[1]: Starting Music Player Daemon... Apr 17 06:57:29 volumio volumio[902]: info: Output device has changed, restarting Shairport Sync Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:29 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:29 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:29 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 06:57:29 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:29 volumio volumio[902]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 06:57:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 17 06:57:29 volumio systemd[1]: mpd.service: Succeeded. Apr 17 06:57:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 06:57:30 volumio systemd[1]: Starting Music Player Daemon... Apr 17 06:57:30 volumio volumio[902]: info: MPD Permissions set Apr 17 06:57:30 volumio volumio[902]: info: MPD Permissions set Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 06:57:30 volumio volumio[902]: info: Starting Shairport Sync Apr 17 06:57:30 volumio volumio[902]: info: Starting Shairport Sync Apr 17 06:57:30 volumio sudo[1493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 17 06:57:30 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:30 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:30 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 06:57:30 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:30 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 06:57:30 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 06:57:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 06:57:30 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 06:57:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 06:57:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 06:57:30 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:30 volumio volumio[902]: info: Shairport-Sync Started Apr 17 06:57:30 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:30 volumio volumio[902]: info: Shairport-Sync Started Apr 17 06:57:30 volumio mpd[1508]: Apr 17 06:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 17 06:57:30 volumio systemd[1]: Started Music Player Daemon. Apr 17 06:57:30 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:30 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Apr 17 06:57:30 volumio volumio[902]: error: MPD error: The expression evaluated to a falsy value: Apr 17 06:57:30 volumio volumio[902]: assert.ok(self.idling) Apr 17 06:57:30 volumio volumio[902]: error: The expression evaluated to a falsy value: Apr 17 06:57:30 volumio volumio[902]: assert.ok(self.idling) Apr 17 06:57:30 volumio volumio[902]: error: updateQueue error: null Apr 17 06:57:33 volumio volumio[902]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 17 06:57:33 volumio volumio[902]: info: CoreStateMachine::getcurrentVolume Apr 17 06:57:33 volumio volumio[902]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 06:57:33 volumio volumio[902]: info: VolumeController:: Volume=94 Mute =false Apr 17 06:57:33 volumio volumio[902]: info: CoreStateMachine::pushState Apr 17 06:57:33 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:33 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Apr 17 06:57:33 volumio volumio[902]: info: CoreStateMachine::updateTrackBlock Apr 17 06:57:33 volumio volumio[902]: info: CorePlayQueue::getTrackBlock Apr 17 06:57:33 volumio volumio[902]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 06:57:33 volumio volumio[902]: info: VolumeController:: Volume=94 Mute =false Apr 17 06:57:33 volumio volumio[902]: info: CoreStateMachine::pushState Apr 17 06:57:33 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:33 volumio volumio[902]: info: CoreCommandRouter::volumioPushState Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:57:35 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:35 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:57:35 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.10 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:35 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 17 06:57:35 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 17 06:57:38 volumio ntpd[797]: Soliciting pool server 103.70.115.65 Apr 17 06:57:39 volumio ntpd[797]: Soliciting pool server 103.199.19.135 Apr 17 06:57:39 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:57:39 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:57:39 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:57:39 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:57:39 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:57:39 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:57:40 volumio ntpd[797]: Soliciting pool server 115.165.161.155 Apr 17 06:57:40 volumio ntpd[797]: Soliciting pool server 27.71.27.209 Apr 17 06:57:41 volumio ntpd[797]: Soliciting pool server 103.184.124.254 Apr 17 06:57:41 volumio ntpd[797]: Soliciting pool server 103.184.124.254 Apr 17 06:57:41 volumio ntpd[797]: Soliciting pool server 103.184.124.254 Apr 17 06:57:42 volumio ntpd[797]: Soliciting pool server 2401:5f80:5001:1f::31f Apr 17 06:57:48 volumio ntpd[797]: receive: Unexpected origin timestamp 0xebaac27b.5ddcf024 does not match aorg 0000000000.00000000 from server@103.70.115.65 xmt 0xebaac27c.812be8b6 Apr 17 06:57:48 volumio ntpd[797]: receive: Unexpected origin timestamp 0xebaac27b.5dd9234c does not match aorg 0000000000.00000000 from server@27.71.27.209 xmt 0xebaac27c.83c55370 Apr 17 06:57:48 volumio ntpd[797]: receive: Unexpected origin timestamp 0xebaac27b.5dd692a9 does not match aorg 0000000000.00000000 from server@103.184.124.254 xmt 0xebaac27c.83830be0 Apr 17 06:57:48 volumio ntpd[797]: receive: Unexpected origin timestamp 0xebaac27b.5dda8248 does not match aorg 0000000000.00000000 from server@115.165.161.155 xmt 0xebaac27c.83e84833 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:58:05 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:58:05 volumio volumio[902]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.10 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 17 06:58:05 volumio volumio[902]: info: Received Get System Info Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:58:05 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 06:58:05 volumio volumio[902]: info: Received Get System Info Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 06:58:05 volumio volumio[902]: info: Discovery: Getting this device information Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 06:58:05 volumio volumio[902]: info: CoreCommandRouter::volumioGetState Apr 17 06:58:05 volumio volumio[902]: info: CorePlayQueue::getTrack 0 Apr 17 06:58:05 volumio volumio[902]: info: Listing playlists Apr 17 06:58:06 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: info: Retrieving Cloud Streaming UI Apr 17 06:58:08 volumio volumio[902]: info: Getting Tidal Cloud Configuration Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: info: Getting Qobuz Cloud Configuration Apr 17 06:58:08 volumio volumio[902]: info: Asking plugin for UI Config Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: info: Getting Spotify Cloud Configuration Apr 17 06:58:08 volumio volumio[902]: info: Asking plugin for UI Config Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: info: Saving Spotify Acccount Apr 17 06:58:08 volumio volumio[902]: info: Got Tidal Cloud Configuration Apr 17 06:58:08 volumio volumio[902]: info: Got it Apr 17 06:58:08 volumio volumio[902]: info: Got it Apr 17 06:58:08 volumio volumio[902]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 06:58:08 volumio volumio[902]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 06:58:08 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 17 06:58:09 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 17 06:58:09 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 17 06:58:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 06:58:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 17 06:58:12 volumio volumio[902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 17 06:58:14 volumio volumio[902]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 06:58:14 volumio volumio[902]: Error: dns service error: unknown Apr 17 06:58:14 volumio volumio[902]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 17 06:58:14 volumio volumio[902]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 17 06:58:14 volumio volumio[902]: errorCode: -65537 Apr 17 06:58:14 volumio volumio[902]: } Apr 17 06:58:14 volumio volumio[902]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 06:58:14 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-17 06:57 Apr 17 06:58:14 volumio sudo[1648]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"