Jan 28 15:50:05 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 28 15:50:12 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 28 15:50:12 volumio volumio[1110]: info: Setting timezone to Asia/Tokyo Jan 28 15:50:12 volumio sudo[2462]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 28 15:50:12 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:12 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:12 volumio sudo[2466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Jan 28 15:50:12 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:12 volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:12 volumio sudo[2470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 28 15:50:12 volumio sudo[2470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:12 volumio sudo[2470]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:12 volumio sudo[2474]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Jan 28 15:50:12 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:12 volumio systemd-timedated[2311]: Changed time zone to 'Asia/Tokyo' (JST). Jan 28 15:50:12 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:12 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 28 15:50:12 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jan 28 15:50:13 volumio volumio[1110]: info: Loading i18n strings for locale ja Jan 28 15:50:14 volumio volumio[1110]: Updating browse sources language Jan 28 15:50:14 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:50:14 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jan 28 15:50:14 volumio volumio[1110]: info: Setting timezone to Asia/Tokyo Jan 28 15:50:14 volumio sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jan 28 15:50:14 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:14 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:14 volumio sudo[2483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Jan 28 15:50:14 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:14 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:14 volumio sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jan 28 15:50:14 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:14 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:14 volumio sudo[2491]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Jan 28 15:50:14 volumio sudo[2491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:14 volumio sudo[2491]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:14 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 28 15:50:14 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 28 15:50:14 volumio sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:50:14 volumio sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:15 volumio sudo[2495]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:16 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jan 28 15:50:16 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jan 28 15:50:16 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 15:50:16 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:16 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 15:50:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 28 15:50:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:21 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 28 15:50:21 volumio volumio[1110]: info: Preparing to save Alsa Options, stopping services first Jan 28 15:50:21 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:21 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:21 volumio volumio[1110]: info: CoreCommandRouter::volumioPause Jan 28 15:50:21 volumio volumio[1110]: info: CoreStateMachine::pause Jan 28 15:50:21 volumio volumio[1110]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"TX-384Khz Hifi Type-C Audio"},"disallowPush":true} Jan 28 15:50:21 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 15:50:22 volumio volumio[1110]: info: Setting mixer PCM for card TX-384Khz Hifi Type-C Audio Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 28 15:50:22 volumio volumio[1110]: info: Updating Volume Controller Parameters: Device: 5 Name: TX-384Khz Hifi Type-C Audio Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 28 15:50:22 volumio volumio[1110]: info: Disabling external Volume Control Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:22 volumio volumio[1110]: info: Preparing to generate the ALSA configuration file Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 28 15:50:22 volumio sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:50:22 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio volumio[1110]: info: VolumeController:: Volume=41 Mute =false Jan 28 15:50:22 volumio volumio[1110]: info: CoreStateMachine::pushState Jan 28 15:50:22 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::volumioPushState Jan 28 15:50:22 volumio sudo[2536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:50:22 volumio sudo[2536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2536]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 15:50:22 volumio volumio[1110]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 15:50:22 volumio volumio[1110]: wlan0 Interface doesn't support scanning : Device or resource busy Jan 28 15:50:22 volumio volumio[1110]: info: Cannot use regular scanning, forcing with ap-force Jan 28 15:50:22 volumio sudo[2551]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 15:50:22 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: command failed: Device or resource busy (-16) Jan 28 15:50:22 volumio volumio[1110]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 15:50:22 volumio volumio[1110]: command failed: Device or resource busy (-16) Jan 28 15:50:22 volumio volumio[1110]: info: Asound.conf file written Jan 28 15:50:22 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 15:50:22 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: No state is present for card Intel Jan 28 15:50:22 volumio volumio[1110]: Found hardware: "HDA-Intel" "Conexant Generic" "HDA:14f15045,17aa20dc,00100100" "0x17aa" "0x20ac" Jan 28 15:50:22 volumio volumio[1110]: Hardware is initialized using a generic method Jan 28 15:50:22 volumio volumio[1110]: No state is present for card Intel Jan 28 15:50:22 volumio volumio[1110]: No state is present for card Audio Jan 28 15:50:22 volumio volumio[1110]: Found hardware: "USB-Audio" "USB Mixer" "USB0bda:4c07" "" "" Jan 28 15:50:22 volumio volumio[1110]: Hardware is initialized using a generic method Jan 28 15:50:22 volumio volumio[1110]: No state is present for card Audio Jan 28 15:50:22 volumio volumio[1110]: info: Output device has changed, restarting MPD Jan 28 15:50:22 volumio sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:50:22 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio volumio[1110]: info: Output device has changed, restarting Shairport Sync Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:50:22 volumio volumio[1110]: info: Output device has changed, restarting MPD Jan 28 15:50:22 volumio sudo[2565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:50:22 volumio sudo[2565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio volumio[1110]: info: Output device has changed, restarting Shairport Sync Jan 28 15:50:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:22 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:50:22 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:50:22 volumio sudo[2574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:50:22 volumio volumio[1110]: info: MPD Permissions set Jan 28 15:50:22 volumio volumio[1110]: info: MPD Permissions set Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:22 volumio volumio[1110]: info: Starting Shairport Sync Jan 28 15:50:22 volumio volumio[1110]: info: Starting Shairport Sync Jan 28 15:50:22 volumio sudo[2599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:50:22 volumio sudo[2599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio sudo[2601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:50:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:50:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:50:22 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:22 volumio systemd[1]: shairport-sync.service: Consumed 1.281s CPU time. Jan 28 15:50:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:22 volumio sudo[2599]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:50:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:50:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:22 volumio volumio[1110]: info: Shairport-Sync Started Jan 28 15:50:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:22 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:22 volumio volumio[1110]: info: Shairport-Sync Started Jan 28 15:50:22 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 15:50:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 15:50:22 volumio systemd[1]: mpd.service: Consumed 2.459s CPU time. Jan 28 15:50:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 15:50:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 15:50:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 15:50:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 15:50:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 28 15:50:22 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:22 volumio sudo[2621]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 15:50:22 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:22 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:23 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:23 volumio mpd[2623]: 2026-01-28T15:50:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 15:50:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 15:50:23 volumio sudo[2565]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:23 volumio volumio[1110]: error: updateQueue error: null Jan 28 15:50:23 volumio sudo[2574]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:23 volumio volumio[1110]: error: updateQueue error: null Jan 28 15:50:26 volumio volumio[1110]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 28 15:50:26 volumio volumio[1110]: info: CoreStateMachine::getcurrentVolume Jan 28 15:50:26 volumio volumio[1110]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:50:26 volumio volumio[1110]: info: VolumeController:: Volume=41 Mute =false Jan 28 15:50:26 volumio volumio[1110]: info: CoreStateMachine::pushState Jan 28 15:50:26 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:26 volumio volumio[1110]: info: CoreCommandRouter::volumioPushState Jan 28 15:50:26 volumio volumio[1110]: info: CoreStateMachine::updateTrackBlock Jan 28 15:50:26 volumio volumio[1110]: info: CorePlayQueue::getTrackBlock Jan 28 15:50:26 volumio volumio[1110]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:50:26 volumio volumio[1110]: info: VolumeController:: Volume=41 Mute =false Jan 28 15:50:26 volumio volumio[1110]: info: CoreStateMachine::pushState Jan 28 15:50:26 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:26 volumio volumio[1110]: info: CoreCommandRouter::volumioPushState Jan 28 15:50:30 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Jan 28 15:50:30 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Jan 28 15:50:30 volumio sudo[2662]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:50:30 volumio sudo[2662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:30 volumio sudo[2662]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:30 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jan 28 15:50:30 volumio volumio[1110]: info: Saving new wireless network Jan 28 15:50:30 volumio sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jan 28 15:50:30 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:30 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:30 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 28 15:50:30 volumio sudo[2668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 28 15:50:30 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:30 volumio systemd[1]: Stopping wireless.service - Wireless Services... Jan 28 15:50:30 volumio systemd[1]: wireless.service: Deactivated successfully. Jan 28 15:50:30 volumio systemd[1]: Stopped wireless.service - Wireless Services. Jan 28 15:50:30 volumio systemd[1]: Starting wireless.service - Wireless Services... Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Jan 28 15:50:31 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jan 28 15:50:31 volumio dnsmasq[1938]: exiting on receipt of SIGTERM Jan 28 15:50:31 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Jan 28 15:50:31 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jan 28 15:50:31 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jan 28 15:50:31 volumio dhcpcd[846]: wlan0: carrier lost Jan 28 15:50:31 volumio avahi-daemon[718]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 28 15:50:31 volumio avahi-daemon[718]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 28 15:50:31 volumio avahi-daemon[718]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 28 15:50:31 volumio avahi-daemon[718]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 28 15:50:31 volumio avahi-daemon[718]: New relevant interface wlan0.IPv4 for mDNS. Jan 28 15:50:31 volumio avahi-daemon[718]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jan 28 15:50:31 volumio volumio[1110]: info: Discovery: A device disappeared from network Jan 28 15:50:31 volumio volumio[1110]: info: Discovery: Device volumio disappeared from network Jan 28 15:50:31 volumio systemd[1]: hostapd.service: Deactivated successfully. Jan 28 15:50:31 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jan 28 15:50:31 volumio volumio[1110]: info: Discovery: A device disappeared from network Jan 28 15:50:31 volumio sudo[2702]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 15:50:31 volumio sudo[2702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:31 volumio avahi-daemon[718]: Withdrawing address record for 192.168.211.1 on wlan0. Jan 28 15:50:31 volumio avahi-daemon[718]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jan 28 15:50:31 volumio avahi-daemon[718]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 28 15:50:31 volumio sudo[2702]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:31 volumio volumio[1110]: info: Discovery: A device disappeared from network Jan 28 15:50:31 volumio sudo[2714]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 15:50:31 volumio volumio[1110]: info: Discovery: A device disappeared from network Jan 28 15:50:31 volumio sudo[2714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:31 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 28 15:50:31 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 28 15:50:31 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 28 15:50:31 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 28 15:50:31 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 28 15:50:31 volumio sudo[2714]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: Cleaning previous... Jan 28 15:50:31 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 28 15:50:31 volumio welcome[2716]: Resolved ip:[0] Jan 28 15:50:31 volumio sudo[2718]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 15:50:31 volumio sudo[2718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:31 volumio sudo[2718]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:31 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 28 15:50:31 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms Jan 28 15:50:31 volumio wireless.js[2670]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 00:19:7d:21:8c:3a) Jan 28 15:50:31 volumio sudo[2729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 28 15:50:31 volumio sudo[2729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:31 volumio sudo[2729]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:31 volumio sudo[2737]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 28 15:50:31 volumio sudo[2737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:31 volumio sudo[2742]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:31 volumio sudo[2742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:31 volumio sudo[2742]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:32 volumio sudo[2737]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:32 volumio wireless.js[2670]: WIRELESS.JS - INFO: Regdomain already correct: JP Jan 28 15:50:32 volumio wireless.js[2670]: WIRELESS.JS - INFO: Start wireless flow Jan 28 15:50:32 volumio wireless.js[2670]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Jan 28 15:50:32 volumio sudo[2749]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 15:50:32 volumio sudo[2749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:32 volumio sudo[2749]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:32 volumio sudo[2751]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 15:50:32 volumio sudo[2751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:32 volumio sudo[2751]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:32 volumio wireless.js[2670]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jan 28 15:50:32 volumio wireless.js[2670]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 00:19:7d:21:8c:3a, USB: false) Jan 28 15:50:32 volumio wpa_supplicant[2757]: Successfully initialized wpa_supplicant Jan 28 15:50:32 volumio sudo[2761]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 15:50:32 volumio sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:32 volumio sudo[2761]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:32 volumio sudo[2767]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:32 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:32 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:33 volumio wireless.js[2670]: WIRELESS.JS - INFO: DHCP IP fallback Jan 28 15:50:33 volumio wireless.js[2670]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Jan 28 15:50:33 volumio wireless.js[2670]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: SME: Trying to authenticate with 0a:db:8d:23:90:f4 (SSID='Pixel_8420' freq=2462 MHz) Jan 28 15:50:33 volumio kernel: wlan0: authenticate with 0a:db:8d:23:90:f4 (local address=00:19:7d:21:8c:3a) Jan 28 15:50:33 volumio kernel: wlan0: send auth to 0a:db:8d:23:90:f4 (try 1/3) Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: Trying to associate with 0a:db:8d:23:90:f4 (SSID='Pixel_8420' freq=2462 MHz) Jan 28 15:50:33 volumio kernel: wlan0: authenticated Jan 28 15:50:33 volumio kernel: wlan0: associate with 0a:db:8d:23:90:f4 (try 1/3) Jan 28 15:50:33 volumio kernel: wlan0: RX AssocResp from 0a:db:8d:23:90:f4 (capab=0x1431 status=0 aid=3) Jan 28 15:50:33 volumio kernel: wlan0: associated Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: Associated with 0a:db:8d:23:90:f4 Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: WPA: Key negotiation completed with 0a:db:8d:23:90:f4 [PTK=CCMP GTK=CCMP] Jan 28 15:50:33 volumio wpa_supplicant[2758]: wlan0: CTRL-EVENT-CONNECTED - Connection to 0a:db:8d:23:90:f4 completed [id=0 id_str=] Jan 28 15:50:33 volumio dhcpcd[846]: wlan0: carrier acquired Jan 28 15:50:33 volumio dhcpcd[846]: wlan0: connected to Access Point: Pixel_8420 Jan 28 15:50:33 volumio dhcpcd[846]: wlan0: IAID 7d:21:8c:3a Jan 28 15:50:33 volumio sudo[2775]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:33 volumio sudo[2775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:33 volumio sudo[2775]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:34 volumio dhcpcd[846]: wlan0: soliciting a DHCP lease Jan 28 15:50:34 volumio dhcpcd[846]: wlan0: probing address 10.115.200.125/24 Jan 28 15:50:34 volumio wireless.js[2670]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> COMPLETED (duration: 0ms) Jan 28 15:50:34 volumio wireless.js[2670]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Jan 28 15:50:34 volumio wireless.js[2670]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 0a:db:8d:23:90:f4 Jan 28 15:50:34 volumio wireless.js[2670]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Jan 28 15:50:34 volumio dhcpcd[846]: wlan0: soliciting an IPv6 router Jan 28 15:50:34 volumio sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:34 volumio sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:34 volumio sudo[2787]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:35 volumio ntpd[903]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=80 secs Jan 28 15:50:35 volumio sudo[2790]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Jan 28 15:50:35 volumio sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:35 volumio dhcpcd[846]: control command: /sbin/dhcpcd wlan0 Jan 28 15:50:35 volumio dhcpcd[846]: control_free: No such file or directory Jan 28 15:50:35 volumio sudo[2790]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:35 volumio sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:35 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:35 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:36 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:36 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:36 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:36 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:36 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:36 volumio sudo[2798]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:36 volumio sudo[2798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:36 volumio sudo[2798]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:37 volumio wireless.js[2670]: WIRELESS.JS - INFO: Start ap Jan 28 15:50:37 volumio wireless.js[2670]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 28 15:50:37 volumio iwconfig[2806]: Error for wireless request "Set Power Management" (8B2C) : Jan 28 15:50:37 volumio iwconfig[2806]: SET failed on device wlan0 ; Operation not supported. Jan 28 15:50:37 volumio systemd[1]: Started wireless.service - Wireless Services. Jan 28 15:50:37 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:37 volumio sudo[2808]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:37 volumio sudo[2808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:37 volumio sudo[2808]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:38 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:38 volumio wireless.js[2670]: WIRELESS.JS - INFO: trying... Jan 28 15:50:38 volumio sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 15:50:38 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:38 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:38 volumio sudo[2837]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:38 volumio sudo[2837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:38 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:38 volumio wireless.js[2670]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Jan 28 15:50:38 volumio sudo[2840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:38 volumio sudo[2840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:38 volumio sudo[2840]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:39 volumio dhcpcd[846]: wlan0: leased 10.115.200.125 for 3599 seconds Jan 28 15:50:39 volumio avahi-daemon[718]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.115.200.125. Jan 28 15:50:39 volumio dhcpcd[846]: wlan0: adding route to 10.115.200.0/24 Jan 28 15:50:39 volumio dhcpcd[846]: wlan0: adding default route via 10.115.200.236 Jan 28 15:50:39 volumio avahi-daemon[718]: New relevant interface wlan0.IPv4 for mDNS. Jan 28 15:50:39 volumio avahi-daemon[718]: Registering new address record for 10.115.200.125 on wlan0.IPv4. Jan 28 15:50:39 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 28 15:50:39 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jan 28 15:50:39 volumio systemd[1]: welcome.service: Deactivated successfully. Jan 28 15:50:39 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 28 15:50:39 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 28 15:50:39 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 28 15:50:39 volumio welcome[2853]: Resolved ip:[1] 10.115.200.125 Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: trying... Jan 28 15:50:39 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 28 15:50:39 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jan 28 15:50:39 volumio sudo[2876]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 15:50:39 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:39 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:39 volumio sudo[2879]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:39 volumio sudo[2879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:39 volumio sudo[2879]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 10.115.200.125, ipV6 is undefined Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: Connected to SSID: Pixel_8420 Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: It's done! AP Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: Restarting avahi-daemon... Jan 28 15:50:39 volumio sudo[2884]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Jan 28 15:50:39 volumio sudo[2884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:50:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:50:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:50:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:39 volumio systemd[1]: shairport-sync.service: Consumed 1.475s CPU time. Jan 28 15:50:39 volumio avahi-daemon[718]: Got SIGTERM, quitting. Jan 28 15:50:39 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 28 15:50:39 volumio avahi-daemon[718]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.115.200.125. Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:39 volumio avahi-daemon[718]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:39 volumio volumio[1110]: error: Discovery: Advertisement error: Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: error: Discovery: advertisement error: Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Stopping Advertising Immediately Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Stopping existing advertisement Jan 28 15:50:39 volumio dbus-daemon[719]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.40' (uid=0 pid=1258 comm="/usr/sbin/smbd --foreground --no-process-group") Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:39 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:39 volumio avahi-daemon[718]: avahi-daemon 0.8 exiting. Jan 28 15:50:39 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Jan 28 15:50:39 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 28 15:50:39 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 28 15:50:39 volumio avahi-daemon[2888]: Process 718 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jan 28 15:50:39 volumio avahi-daemon[2888]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jan 28 15:50:39 volumio avahi-daemon[2888]: Successfully dropped root privileges. Jan 28 15:50:39 volumio avahi-daemon[2888]: avahi-daemon 0.8 starting up. Jan 28 15:50:39 volumio dbus-daemon[719]: [system] Successfully activated service 'org.freedesktop.Avahi' Jan 28 15:50:39 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 28 15:50:39 volumio sudo[2884]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:39 volumio avahi-daemon[2888]: Successfully called chroot(). Jan 28 15:50:39 volumio avahi-daemon[2888]: Successfully dropped remaining capabilities. Jan 28 15:50:39 volumio avahi-daemon[2888]: No service file found in /etc/avahi/services. Jan 28 15:50:39 volumio avahi-daemon[2888]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.115.200.125. Jan 28 15:50:39 volumio avahi-daemon[2888]: New relevant interface wlan0.IPv4 for mDNS. Jan 28 15:50:39 volumio avahi-daemon[2888]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 28 15:50:39 volumio avahi-daemon[2888]: New relevant interface lo.IPv4 for mDNS. Jan 28 15:50:39 volumio avahi-daemon[2888]: Network interface enumeration completed. Jan 28 15:50:39 volumio avahi-daemon[2888]: Registering new address record for 10.115.200.125 on wlan0.IPv4. Jan 28 15:50:39 volumio avahi-daemon[2888]: Registering new address record for 127.0.0.1 on lo.IPv4. Jan 28 15:50:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:50:39 volumio wireless.js[2670]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 28 15:50:39 volumio sudo[2907]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:39 volumio sudo[2907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:39 volumio sudo[2907]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:39 volumio sudo[2911]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 15:50:39 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:39 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:40 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:40 volumio avahi-daemon[2888]: Server startup complete. Host name is volumio.local. Local service cookie is 1419893852. Jan 28 15:50:40 volumio volumio[1110]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Jan 28 15:50:40 volumio volumio[1110]: info: Discovery: Restarting Advertising Jan 28 15:50:40 volumio volumio[1110]: info: Discovery: Restarting Browsing Jan 28 15:50:40 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:41 volumio ntpd[903]: IO: Listen normally on 4 wlan0 10.115.200.125:123 Jan 28 15:50:41 volumio ntpd[903]: IO: new interface(s) found: waking up resolver Jan 28 15:50:41 volumio ntpd[903]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 28 15:50:41 volumio ntpd[903]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 142.91.108.61 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 162.159.200.123 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 157.7.213.62 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 92.112.53.182 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::5c70:35b6 Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::8e5b:6c3d Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::a29f:c87b Jan 28 15:50:41 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::2efa:fde3 Jan 28 15:50:41 volumio ntpd[903]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jan 28 15:50:42 volumio ntpd[903]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 28 15:50:42 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:42 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jan 28 15:50:42 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jan 28 15:50:42 volumio sudo[2940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:42 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:42 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:42 volumio sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 15:50:42 volumio sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:42 volumio sudo[2944]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:42 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 28 15:50:42 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:42 volumio ntpd[903]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 85.121.50.82 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 167.179.119.205 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 45.76.211.39 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 172.105.192.74 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 2406:da14:10c4:9aa0:123:: Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 2001:678:8::123 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 2401:d560:0:105:1c00:daff:fe00:219 Jan 28 15:50:42 volumio ntpd[903]: DNS: Pool taking: 2001:418:3ff::53 Jan 28 15:50:42 volumio ntpd[903]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jan 28 15:50:43 volumio ntpd[903]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 28 15:50:43 volumio ntpd[903]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 162.159.200.1 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 129.250.35.250 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 172.233.91.137 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 43.167.237.61 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::81fa:23fb Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::ace9:5b89 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::a29f:c801 Jan 28 15:50:43 volumio ntpd[903]: DNS: Pool taking: 64:ff9b::81fa:23fa Jan 28 15:50:43 volumio ntpd[903]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:44 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jan 28 15:50:44 volumio volumio[1110]: info: Discovery: Restarting Advertising Jan 28 15:50:44 volumio volumio[1110]: info: Discovery: Restart already pending, ignoring duplicate call Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 28 15:50:44 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 28 15:50:44 volumio sudo[2955]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:50:44 volumio sudo[2955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:44 volumio sudo[2957]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:50:44 volumio sudo[2957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:44 volumio sudo[2957]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:44 volumio volumio[1110]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 15:50:44 volumio volumio[1110]: wlan0 Interface doesn't support scanning : Device or resource busy Jan 28 15:50:44 volumio volumio[1110]: info: Cannot use regular scanning, forcing with ap-force Jan 28 15:50:44 volumio sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 15:50:44 volumio sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:45 volumio sudo[2962]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:45 volumio volumio[1110]: command failed: Device or resource busy (-16) Jan 28 15:50:45 volumio volumio[1110]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 28 15:50:45 volumio volumio[1110]: command failed: Device or resource busy (-16) Jan 28 15:50:45 volumio volumio[1110]: info: Discovery: Started advertising with name: Volumio Jan 28 15:50:46 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin bluetooth to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin multiroom to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin metavolumio to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin cd_controller to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 28 15:50:46 volumio volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 28 15:50:47 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:47 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:50:47 volumio volumio[1110]: info: Starting MyVolumio Remote Streaming Endpoints Jan 28 15:50:47 volumio volumio[1110]: info: MyVolumio not started Jan 28 15:50:47 volumio volumio[1110]: info: Initializing device activation check Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 28 15:50:47 volumio volumio[1110]: info: Streaming services startup Jan 28 15:50:47 volumio volumio[1110]: info: Starting Streaming Daemon Jan 28 15:50:47 volumio volumio[1110]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 28 15:50:47 volumio sudo[2969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 15:50:47 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:47 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 28 15:50:47 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 28 15:50:47 volumio volumio[1110]: info: Refreshing Cached IP Addresses Jan 28 15:50:48 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:48 volumio sudo[2977]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 28 15:50:48 volumio sudo[2977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:48 volumio sudo[2978]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:50:48 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:48 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:48 volumio sudo[2980]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:48 volumio volumio[1110]: error: Cannot start Volumio Streaming Daemon Jan 28 15:50:48 volumio sudo[2980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:48 volumio sudo[2977]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:48 volumio sudo[2980]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:48 volumio volumio[1110]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 15:50:48 volumio volumio[1110]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:48 volumio volumio[1110]: info: Retrieving Cloud Streaming UI Jan 28 15:50:48 volumio volumio[1110]: info: Getting Tidal Cloud Configuration Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:48 volumio volumio[1110]: info: Getting Qobuz Cloud Configuration Jan 28 15:50:48 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:48 volumio volumio[1110]: info: Getting Spotify Cloud Configuration Jan 28 15:50:48 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:48 volumio volumio[1110]: info: Saving Spotify Acccount Jan 28 15:50:48 volumio volumio[1110]: info: Got Tidal Cloud Configuration Jan 28 15:50:48 volumio volumio[1110]: info: Got it Jan 28 15:50:48 volumio volumio[1110]: info: Got it Jan 28 15:50:48 volumio volumio[1110]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:48 volumio volumio[1110]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 28 15:50:48 volumio volumio[1110]: info: Volumio Network Manager: Network status updated: 2 Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jan 28 15:50:48 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jan 28 15:50:50 volumio ntpd[903]: CLOCK: time stepped by 1.636990 Jan 28 15:50:50 volumio ntpd[903]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jan 28 15:50:51 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:51 volumio volumio[1110]: info: Retrieving Cloud Streaming UI Jan 28 15:50:51 volumio volumio[1110]: info: Getting Tidal Cloud Configuration Jan 28 15:50:51 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:51 volumio volumio[1110]: info: Getting Qobuz Cloud Configuration Jan 28 15:50:51 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:51 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:51 volumio volumio[1110]: info: Getting Spotify Cloud Configuration Jan 28 15:50:51 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:51 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:51 volumio volumio[1110]: info: Saving Spotify Acccount Jan 28 15:50:51 volumio volumio[1110]: info: Got Tidal Cloud Configuration Jan 28 15:50:51 volumio volumio[1110]: info: Got it Jan 28 15:50:51 volumio volumio[1110]: info: Got it Jan 28 15:50:51 volumio volumio[1110]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 15:50:51 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:51 volumio volumio[1110]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 28 15:50:51 volumio sudo[2955]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:52 volumio volumio[1110]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Jan 28 15:50:52 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Jan 28 15:50:52 volumio volumio[1110]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') Jan 28 15:50:53 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:53 volumio volumio[1110]: info: Retrieving Cloud Streaming UI Jan 28 15:50:53 volumio volumio[1110]: info: Getting Tidal Cloud Configuration Jan 28 15:50:53 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:53 volumio volumio[1110]: info: Getting Qobuz Cloud Configuration Jan 28 15:50:53 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:53 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:53 volumio volumio[1110]: info: Getting Spotify Cloud Configuration Jan 28 15:50:53 volumio volumio[1110]: info: Asking plugin for UI Config Jan 28 15:50:53 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:53 volumio volumio[1110]: info: Saving Spotify Acccount Jan 28 15:50:53 volumio volumio[1110]: info: Got Tidal Cloud Configuration Jan 28 15:50:53 volumio volumio[1110]: info: Got it Jan 28 15:50:53 volumio volumio[1110]: info: Got it Jan 28 15:50:53 volumio volumio[1110]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 15:50:53 volumio volumio[1110]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 15:50:53 volumio volumio[1110]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::Reload Ui Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jan 28 15:50:55 volumio volumio[1110]: info: Wizard terminated Successfully Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:50:55 volumio volumio[1110]: info: Discovery: adding 1101d482-403e-494d-bc52-df2014a584f9 Jan 28 15:50:55 volumio volumio[1110]: info: Discovery: Found device Volumio Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:55 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:55 volumio volumio[1110]: info: Discovery: this is already registered, 1101d482-403e-494d-bc52-df2014a584f9 Jan 28 15:50:55 volumio volumio[1110]: info: Discovery: Found device Volumio Jan 28 15:50:55 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:55 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:56 volumio volumio[1110]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 28 15:50:57 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:57 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 15:50:57 volumio volumio[1110]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:58 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 15:50:58 volumio volumio[1110]: info: Received Get System Info Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:50:58 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:58 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:58 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:58 volumio volumio[1110]: info: Listing playlists Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 15:50:58 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:58 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:50:59 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:59 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:50:59 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125:3000 from 10.115.200.45 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 15:50:59 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125:3000 from 10.115.200.45 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:50:59 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:59 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:50:59 volumio volumio[1110]: info: Received Get System Info Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:50:59 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:50:59 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:50:59 volumio sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:50:59 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:59 volumio sudo[3030]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:50:59 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:59 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:59 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:59 volumio sudo[3032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 15:50:59 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:50:59 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root Jan 28 15:50:59 volumio volumio[1110]: info: Upmpdcli Daemon Started Jan 28 15:50:59 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:00 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:00 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 28 15:51:00 volumio sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 28 15:51:00 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 15:51:00 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3057]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio sudo[3063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 15:51:00 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 15:51:00 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3063]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio sudo[3069]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio sudo[3074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:51:00 volumio sudo[3074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3079]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:51:00 volumio sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:00 volumio sudo[3074]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio sudo[3079]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:00 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:00 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:00 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:00 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:00 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 15:51:00 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125:3000 from 10.115.200.45 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 15:51:00 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 15:51:03 volumio sudo[3099]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:51:03 volumio sudo[3100]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:51:03 volumio sudo[3099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:03 volumio sudo[3100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:03 volumio sudo[3099]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:03 volumio sudo[3100]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:03 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125 from 10.115.200.45 UA: Mozilla/5.0 (Linux; Android 12; moto g(50) 5G Build/S1RSS32.38-20-12-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 28 15:51:03 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 15:51:03 volumio volumio[1110]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 28 15:51:04 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:04 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:04 volumio sudo[3106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:51:04 volumio sudo[3106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:04 volumio sudo[3106]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:04 volumio sudo[3108]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:51:04 volumio sudo[3108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:04 volumio sudo[3108]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:04 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125 from 10.115.200.45 UA: Mozilla/5.0 (Linux; Android 12; moto g(50) 5G Build/S1RSS32.38-20-12-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.192 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:05 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 15:51:05 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:05 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:05 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:05 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:05 volumio volumio[1110]: info: Listing playlists Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 15:51:05 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 15:51:06 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:51:06 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:06 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:06 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:06 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:06 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:06 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:06 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:07 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 28 15:51:07 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 15:51:08 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:51:11 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:51:11 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:11 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:11 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:11 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:11 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:11 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:11 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:12 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 28 15:51:13 volumio volumio[1110]: info: MyVolumio login type: Token Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:13 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:13 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:13 volumio volumio[1110]: verbose: New Socket.io Connection to 10.115.200.125:3000 from 10.115.200.45 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 15:51:13 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 15:51:14 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 28 15:51:15 volumio volumio[1110]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 28 15:51:16 volumio volumio[1110]: info: CoreCommandRouter::volumioGetQueue Jan 28 15:51:16 volumio volumio[1110]: info: CoreStateMachine::getQueue Jan 28 15:51:16 volumio volumio[1110]: info: CorePlayQueue::getQueue Jan 28 15:51:16 volumio volumio[1110]: info: MyVolumio token set successfully Jan 28 15:51:16 volumio volumio[1110]: info: MYVOLUMIO: Adding device Jan 28 15:51:16 volumio volumio[1110]: info: MYVOLUMIO: Evaluating Server Jan 28 15:51:17 volumio volumio[1110]: info: MyVolumio status changed Jan 28 15:51:17 volumio volumio[1110]: info: Streaming services startup Jan 28 15:51:17 volumio volumio[1110]: info: Starting Streaming Daemon Jan 28 15:51:17 volumio volumio[1110]: info: Removing browser output: myVolumio user plan is not superstar Jan 28 15:51:17 volumio volumio[1110]: info: Removing audio output: Jan 28 15:51:17 volumio volumio[1110]: info: Stoppping Tunnel 1 Jan 28 15:51:17 volumio sudo[3164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 15:51:17 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:51:17 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:17 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:17 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:17 volumio sudo[3166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 28 15:51:17 volumio sudo[3166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:51:17 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:17 volumio volumio[1110]: error: Cannot start Volumio Streaming Daemon Jan 28 15:51:17 volumio volumio[1110]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 15:51:17 volumio volumio[1110]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 28 15:51:17 volumio sudo[3166]: pam_unix(sudo:session): session closed for user root Jan 28 15:51:17 volumio volumio[1110]: info: Remote SSH Stopped Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:51:17 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:17 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:17 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 15:51:17 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:17 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:17 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:17 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 15:51:18 volumio volumio[1110]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 28 15:51:18 volumio volumio[1110]: info: Received Get System Version Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:51:18 volumio volumio[1110]: info: Received Get System Info Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 15:51:18 volumio volumio[1110]: info: Discovery: Getting this device information Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 28 15:51:18 volumio volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 15:51:18 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 15:51:18 volumio volumio[1110]: info: CURURI: artists:// Jan 28 15:51:18 volumio volumio[1110]: info: Preload queue cleared Jan 28 15:51:20 volumio volumio[1110]: info: Setting Geolocation for MyVolumio to us3 Jan 28 15:51:20 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:51:20 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:51:20 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:51:20 volumio volumio[1110]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 15:51:20 volumio volumio[1110]: info: CURURI: artists://Ado Jan 28 15:51:20 volumio volumio[1110]: info: Preload queue cleared Jan 28 15:51:20 volumio volumio[1110]: info: Preload queue cleared Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/bin.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/CAT'S EYE.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Limbo.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Sunny Wave.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/エゴロック.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/シザーハンズ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/シュガーバイン.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ビバハピ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ブラック★ロックシューター.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/マトリョシカ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/十戒.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/君の体温.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/夏夜のマジック.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/夜のくじら.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/従属ふりったー.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/怪盗・窪園チヨコは絶対ミスらない.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/恋愛裁判.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/桜日和とタイムマシン.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/橙ゲノム.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/脳内革命ガール.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/邪魔.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/馬鹿.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Stay Gold.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/16ビットガール.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/BINKUSUNO SAKE.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/DIGNITY.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Dried Flowers.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Hello Signals.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Imagine Dragons - Take Me To The Beach (feat. Ado) (Official Lyric Video).mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Overdose.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/unrave l(live).mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Villain.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/アクシデントコーディネイター.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/アディショナルメモリー.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/いばら.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/エルフ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/きっとコースター.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/キライキライジガヒダイ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ザネリ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/シャルル Prod. by キタニタツヤ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ショコラカタブラ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/とても素敵な六月でした.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/バスケットワームBasket Worm.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ハングリーニコル.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ヒステリックナイトガール.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ヒバナ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ブリキノダンス.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ボッカデラベリタ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/メーベル.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ラストリゾート.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ラブデストロイ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/リベリオン.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ロックスター.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/わたしに花束.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/世界のつづきウタ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/丸の内サディスティック.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/半色の感傷.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/可愛くてごめん.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/合唱Mrシャーデンフロイデ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/向日葵.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/唄.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/嗚呼素晴らしきニャン生.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/恋は戦争.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/愛して愛して愛して.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/愛を伝えたいだとか.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/新人類.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/東京は夜.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/渇愛論II.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/神っほいな.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/罪と罰.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/行方知れず.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/風のゆくえウタ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Episode X.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/アンチシステム's (feat. Ado).mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/うっせぇわ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ギラギラ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ラブカ!.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/レディメイド.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/初夏.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/抜け空.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/新時代.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/永遠のあくる日.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/私は最強.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/踊.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/逆光 (ウタ from ONE PIECE FILM RED).mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/阿修羅ちゃん.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Ready Steady.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/メルティランドナイトメア.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/飾りじゃないのよ涙は.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ドメスティックでバイオレンス.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/FREEDOM.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/MIRROR.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ウタカタララバイ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/オールナイトレディオ.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/ダーリンダンス.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/プリキュア5スマイル go go.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/Tot Musica.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/うみなおし.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/花火.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Preloading song: music-library/USB/ボリューム/インタビュア.mp3 Jan 28 15:51:20 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/bin.mp3 in service mpd Jan 28 15:51:20 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2Fbin.mp3&metadata=false Jan 28 15:51:20 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/bin.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/CAT'S EYE.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2FCAT'S%20EYE.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/CAT'S EYE.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/Limbo.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2FLimbo.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/Limbo.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/Sunny Wave.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2FSunny%20Wave.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/Sunny Wave.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/エゴロック.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%82%A8%E3%82%B4%E3%83%AD%E3%83%83%E3%82%AF.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/エゴロック.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/シザーハンズ.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%82%B7%E3%82%B6%E3%83%BC%E3%83%8F%E3%83%B3%E3%82%BA.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/シザーハンズ.mp3 Jan 28 15:51:21 volumio volumio[1110]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/シュガーバイン.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%82%B7%E3%83%A5%E3%82%AB%E3%82%99%E3%83%BC%E3%83%8F%E3%82%99%E3%82%A4%E3%83%B3.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/シュガーバイン.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/ビバハピ.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%83%93%E3%83%90%E3%83%8F%E3%83%94.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/ビバハピ.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/ブラック★ロックシューター.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%83%96%E3%83%A9%E3%83%83%E3%82%AF%E2%98%85%E3%83%AD%E3%83%83%E3%82%AF%E3%82%B7%E3%83%A5%E3%83%BC%E3%82%BF%E3%83%BC.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/ブラック★ロックシューター.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/マトリョシカ.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E3%83%9E%E3%83%88%E3%83%AA%E3%83%A7%E3%82%B7%E3%82%AB.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/マトリョシカ.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/十戒.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E5%8D%81%E6%88%92.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/十戒.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/君の体温.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E5%90%9B%E3%81%AE%E4%BD%93%E6%B8%A9.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/君の体温.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/夏夜のマジック.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E5%A4%8F%E5%A4%9C%E3%81%AE%E3%83%9E%E3%82%B7%E3%82%99%E3%83%83%E3%82%AF.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/夏夜のマジック.mp3 Jan 28 15:51:21 volumio volumio[1110]: info: Exploding uri music-library/USB/ボリューム/夜のくじら.mp3 in service mpd Jan 28 15:51:21 volumio volumio[1110]: info: ALBUMART /albumart?cacheid=658&web=Ado//extralarge&path=%2Fmnt%2FUSB%2F%E3%83%9C%E3%83%AA%E3%83%A5%E3%83%BC%E3%83%A0%2F%E5%A4%9C%E3%81%AE%E3%81%8F%E3%81%97%E3%82%99%E3%82%89.mp3&metadata=false Jan 28 15:51:21 volumio volumio[1110]: info: URI /mnt/USB/ボリューム/夜のくじら.mp3 Jan 28 15:51:21 volumio volumio[1110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:51:21 volumio volumio[1110]: Error: read ECONNRESET Jan 28 15:51:21 volumio volumio[1110]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Jan 28 15:51:21 volumio volumio[1110]: errno: -104, Jan 28 15:51:21 volumio volumio[1110]: code: 'ECONNRESET', Jan 28 15:51:21 volumio volumio[1110]: syscall: 'read' Jan 28 15:51:21 volumio volumio[1110]: } Jan 28 15:51:21 volumio volumio[1110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:51:21 volumio sudo[3201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 15:50' Jan 28 15:51:21 volumio sudo[3201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"