-- Logs begin at Sun 2024-04-28 15:38:31 JST, end at Sun 2024-04-28 15:42:47 JST. -- Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 28 15:41:53 volumio volumio[795]: info: Getting Alsa Cards List without I2S DAC Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:41:53 volumio volumio[795]: Invalid card number. Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 28 15:41:53 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 15:42:11 volumio volumio[795]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Apr 28 15:42:11 volumio volumio[795]: info: Restoring Previous Volume level: false true Apr 28 15:42:11 volumio volumio[795]: info: VolumeController::SetAlsaVolume100 Apr 28 15:42:11 volumio volumio[795]: info: Volume configurations have been set Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 28 15:42:11 volumio volumio[795]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 28 15:42:11 volumio volumio[795]: info: Disabling external Volume Control Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 15:42:11 volumio volumio[795]: info: CoreStateMachine::pushState Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Apr 28 15:42:11 volumio volumio[795]: info: CoreStateMachine::pushState Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Apr 28 15:42:11 volumio volumio[795]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 28 15:42:11 volumio volumio[795]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 28 15:42:11 volumio volumio[795]: info: Getting Alsa Cards List without I2S DAC Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 15:42:11 volumio volumio[795]: Invalid card number. Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 28 15:42:11 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 15:42:15 volumio volumio[795]: info: VolumeController::SetAlsaVolumeNaN Apr 28 15:42:15 volumio volumio[795]: info: CoreStateMachine::pushState Apr 28 15:42:15 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 15:42:15 volumio volumio[795]: info: CoreCommandRouter::volumioPushState Apr 28 15:42:15 volumio volumio[795]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 28 15:42:28 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Apr 28 15:42:31 volumio volumio[795]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 15:42:31 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 15:42:31 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 28 15:42:31 volumio sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 28 15:42:31 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 15:42:31 volumio sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1287]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 15:42:31 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 28 15:42:31 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 15:42:31 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 15:42:31 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 28 15:42:31 volumio sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:31 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 28 15:42:31 volumio sudo[1310]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:31 volumio sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 15:42:31 volumio sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:35 volumio sudo[1314]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:39 volumio volumio[795]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Apr 28 15:42:39 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Apr 28 15:42:40 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 28 15:42:40 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:40 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:40 volumio volumio[795]: info: Permissions for /etc/hostapd/hostapd.conf Apr 28 15:42:40 volumio volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 28 15:42:40 volumio volumio[795]: info: Discovery: Restarting Advertising due to device name change Apr 28 15:42:40 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 28 15:42:40 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:40 volumio systemd[1]: Starting Wireless Services... Apr 28 15:42:41 volumio wireless.js[1328]: Cleaning previous... Apr 28 15:42:41 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 28 15:42:41 volumio volumio[795]: info: Discovery: A device disappeared from network Apr 28 15:42:41 volumio volumio[795]: info: Discovery: Device volumio disappeared from network Apr 28 15:42:41 volumio volumio[795]: info: Discovery: A device disappeared from network Apr 28 15:42:41 volumio systemd[1]: hostapd.service: Succeeded. Apr 28 15:42:41 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 28 15:42:41 volumio sudo[1342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 28 15:42:41 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:41 volumio avahi-daemon[499]: Withdrawing address record for 192.168.211.1 on wlan0. Apr 28 15:42:41 volumio avahi-daemon[499]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 28 15:42:41 volumio avahi-daemon[499]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 28 15:42:41 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:41 volumio sudo[1344]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 28 15:42:41 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:42 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:42 volumio wireless.js[1328]: Stopped aP Apr 28 15:42:42 volumio sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 15:42:42 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:42 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:42 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 15:42:42 volumio sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 28 15:42:42 volumio sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:42 volumio sudo[1354]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:42 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 15:42:42 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:42 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:42 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 28 15:42:42 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:44 volumio ntpd[639]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=218 secs Apr 28 15:42:44 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Apr 28 15:42:45 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:45 volumio wireless.js[1328]: SETTING APPROPRIATE REG DOMAIN: JP Apr 28 15:42:45 volumio sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 28 15:42:45 volumio sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:45 volumio sudo[1368]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:45 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Apr 28 15:42:45 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:45 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:45 volumio wireless.js[1328]: SUCCESSFULLY SET NEW REGDOMAIN: JP Apr 28 15:42:45 volumio sudo[1377]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 28 15:42:45 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:45 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:45 volumio sudo[1379]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 28 15:42:45 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 15:42:45 volumio volumio[795]: info: Discovery: Started advertising with name: Volumio Apr 28 15:42:45 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:45 volumio wireless.js[1328]: Hotspot is disabled, not starting it Apr 28 15:42:45 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 15:42:45 volumio systemd[1]: wireless.service: Succeeded. Apr 28 15:42:45 volumio systemd[1]: Started Wireless Services. Apr 28 15:42:45 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 28 15:42:45 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Apr 28 15:42:46 volumio volumio[795]: info: Discovery: adding ca076b51-a7f6-4f25-b184-b58ff988071e Apr 28 15:42:46 volumio volumio[795]: info: Discovery: Found device Volumio Apr 28 15:42:46 volumio volumio[795]: info: CoreCommandRouter::volumioGetState Apr 28 15:42:46 volumio volumio[795]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 15:42:46 volumio volumio[795]: Error: The value NaN is not a number Apr 28 15:42:46 volumio volumio[795]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 28 15:42:46 volumio volumio[795]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 28 15:42:46 volumio volumio[795]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 28 15:42:46 volumio volumio[795]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 28 15:42:46 volumio volumio[795]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 28 15:42:46 volumio volumio[795]: at Browser.emit (events.js:315:20) Apr 28 15:42:46 volumio volumio[795]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 28 15:42:46 volumio volumio[795]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 28 15:42:46 volumio volumio[795]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 28 15:42:46 volumio volumio[795]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 28 15:42:46 volumio volumio[795]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 28 15:42:46 volumio volumio[795]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 28 15:42:46 volumio volumio[795]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 28 15:42:46 volumio volumio[795]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 15:42:47 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 15:41 Apr 28 15:42:47 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"