-- Logs begin at Wed 2024-09-04 04:36:47 UTC, end at Wed 2024-09-04 08:29:00 UTC. -- Sep 04 08:28:49 volumio systemd[1]: Time has been changed Sep 04 08:28:49 volumio volumio[1151]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 08:28:49 volumio volumio[1151]: info: [1725438529495] CoreMusicLibrary::Adding element Last_100 Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 08:28:49 volumio volumio[1151]: info: [1725438529503] CoreMusicLibrary::Adding element Webradio Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 08:28:49 volumio volumio[1151]: info: ------------------------------------------- Sep 04 08:28:49 volumio volumio[1151]: info: ----- MyVolumio plugins startup ---- Sep 04 08:28:49 volumio volumio[1151]: info: ------------------------------------------- Sep 04 08:28:49 volumio volumio[1151]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 04 08:28:49 volumio volumio[1151]: info: Loading i18n strings for locale zh Sep 04 08:28:49 volumio volumio[1151]: Updating browse sources language Sep 04 08:28:49 volumio volumio[1151]: Cannot find translation for sourceMedia Servers Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 08:28:49 volumio dhcpcd[1061]: wlan0: carrier lost Sep 04 08:28:49 volumio dhcpcd[1061]: eth0: adding route to 192.168.71.0/24 Sep 04 08:28:49 volumio dhcpcd[1061]: eth0: adding default route via 192.168.71.1 Sep 04 08:28:49 volumio dhcpcd[1061]: wlan0: deleting host route to 192.168.71.250 via 127.0.0.1 Sep 04 08:28:49 volumio dhcpcd[1061]: wlan0: deleting route to 192.168.71.0/24 Sep 04 08:28:49 volumio dhcpcd[1061]: wlan0: deleting default route via 192.168.71.1 Sep 04 08:28:49 volumio avahi-daemon[679]: Withdrawing address record for 192.168.71.250 on wlan0. Sep 04 08:28:49 volumio avahi-daemon[679]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.71.250. Sep 04 08:28:49 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-DISCONNECTED bssid=68:27:5f:d4:9e:fb reason=0 locally_generated=1 Sep 04 08:28:49 volumio avahi-daemon[679]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::initPlayerControls Sep 04 08:28:49 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 08:28:49 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 04 08:28:49 volumio volumio[1151]: info: BOOT COMPLETED Sep 04 08:28:49 volumio volumio[1151]: [Metrics] CommandRouter: 3s 570.28ms Sep 04 08:28:49 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:28:49 volumio sshd[746]: Received SIGHUP; restarting. Sep 04 08:28:49 volumio dhcpcd[1061]: eth0: removing route to 192.168.71.0/24 Sep 04 08:28:49 volumio dhcpcd[1061]: eth0: removing default route via 192.168.71.1 Sep 04 08:28:49 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 04 08:28:49 volumio sshd[746]: Server listening on 0.0.0.0 port 22. Sep 04 08:28:49 volumio sshd[746]: Server listening on :: port 22. Sep 04 08:28:50 volumio volumio[1151]: Starting albumart workers Sep 04 08:28:50 volumio volumio[1151]: Starting albumart workers Sep 04 08:28:50 volumio volumio[1151]: Starting albumart workers Sep 04 08:28:50 volumio wpa_supplicant[1002]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG-5G' Sep 04 08:28:51 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 04 08:28:52 volumio wpa_supplicant[1002]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG-5G' Sep 04 08:28:52 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 04 08:28:53 volumio ntpd[758]: Deleting interface #4 wlan0, 192.168.71.250#123, interface stats: received=0, sent=0, dropped=0, active_time=8 secs Sep 04 08:28:53 volumio ntpd[758]: peers refreshed Sep 04 08:28:54 volumio wpa_supplicant[1002]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG-5G' Sep 04 08:28:54 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 04 08:28:54 volumio volumio[1151]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 04 08:28:54 volumio volumio[1151]: info: CoreCommandRouter::Close All Modals sent Sep 04 08:28:54 volumio volumio[1151]: info: CoreCommandRouter::Close All Modals sent Sep 04 08:28:54 volumio volumio[1151]: Express server listening on port 3000 Sep 04 08:28:54 volumio volumio[1151]: [Metrics] WebUI: 9s 420.95ms Sep 04 08:28:55 volumio volumio[1151]: Volumio Calling Home Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::resetVolumioState Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::getcurrentVolume Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 08:28:55 volumio volumio[1151]: info: Cannot read play queue from file Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::pushState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::updateTrackBlock Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrackBlock Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 08:28:55 volumio volumio[1151]: info: Setting Device type: Raspberry PI Sep 04 08:28:55 volumio volumio[1151]: info: MPD running with PID682 Sep 04 08:28:55 volumio volumio[1151]: ,establishing connection Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::pushState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:28:55 volumio volumio[1151]: info: Sep 04 08:28:55 volumio volumio[1151]: ---------------------------- Client requests Volume 5 Sep 04 08:28:55 volumio volumio[1151]: info: VolumeController::SetAlsaVolume5 Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::pushState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:28:55 volumio volumio[1151]: error: updateQueue error: null Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::setRepeat null single undefined Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::pushState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::setRandom null Sep 04 08:28:55 volumio volumio[1151]: info: CoreStateMachine::pushState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioPushState Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 08:28:55 volumio volumio[1151]: info: Setting volume on startup at 5 Sep 04 08:28:55 volumio volumio[1151]: info: Starting Shairport Sync Sep 04 08:28:55 volumio volumio[1151]: info: Starting Shairport Sync Sep 04 08:28:55 volumio volumio[1151]: info: mDNS: A device disapperared from network Sep 04 08:28:55 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 08:28:55 volumio volumio[1151]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 04 08:28:55 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:28:55 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 08:28:55 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:28:55 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 04 08:28:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 08:28:55 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Sep 04 08:28:55 volumio volumio[1151]: info: mDNS: A device disapperared from network Sep 04 08:28:55 volumio volumio[1151]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 04 08:28:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 04 08:28:55 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 04 08:28:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 08:28:55 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Sep 04 08:28:55 volumio volumio[1151]: info: Shairport-Sync Started Sep 04 08:28:55 volumio volumio[1151]: Error adding Membership: Error: addMembership EINVAL Sep 04 08:28:55 volumio volumio[1151]: info: Shairport-Sync Started Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::volumioGetState Sep 04 08:28:55 volumio volumio[1151]: info: CorePlayQueue::getTrack 0 Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 08:28:55 volumio volumio[1151]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 04 08:28:56 volumio wpa_supplicant[1002]: wlan0: Trying to associate with SSID 'ChinaNet-CRFG-5G' Sep 04 08:28:57 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 04 08:28:57 volumio wpa_supplicant[1002]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ChinaNet-CRFG-5G" auth_failures=1 duration=10 reason=CONN_FAILED Sep 04 08:28:58 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 08:28:58 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:28:58 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 08:28:58 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Sep 04 08:28:58 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:28:58 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 04 08:28:58 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Sep 04 08:28:58 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 08:28:58 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 04 08:28:58 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 08:28:58 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Sep 04 08:28:58 volumio volumio[1151]: info: Upmpdcli Daemon Started Sep 04 08:29:00 volumio volumio[1151]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 08:29:00 volumio volumio[1151]: { Error: addMembership ENODEV Sep 04 08:29:00 volumio volumio[1151]: at _errnoException (util.js:1022:11) Sep 04 08:29:00 volumio volumio[1151]: at Socket.addMembership (dgram.js:615:11) Sep 04 08:29:00 volumio volumio[1151]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Sep 04 08:29:00 volumio volumio[1151]: at ontimeout (timers.js:482:11) Sep 04 08:29:00 volumio volumio[1151]: at tryOnTimeout (timers.js:317:5) Sep 04 08:29:00 volumio volumio[1151]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 04 08:29:00 volumio volumio[1151]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 08:29:00 volumio sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 08:28 Sep 04 08:29:00 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"