-- Logs begin at Sat 2024-11-23 16:17:30 KST, end at Sat 2024-11-23 16:39:58 KST. -- Nov 23 16:38:00 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 23 16:38:00 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 23 16:38:00 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 23 16:38:00 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 23 16:38:02 ella-sonika wpa_supplicant[4589]: wlan0: Failed to initiate sched scan Nov 23 16:38:09 ella-sonika kernel: hwmon hwmon2: Undervoltage detected! Nov 23 16:38:11 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Nov 23 16:38:11 ella-sonika kernel: hwmon hwmon2: Voltage normalised Nov 23 16:38:11 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Nov 23 16:38:11 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Nov 23 16:38:11 ella-sonika kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Nov 23 16:38:14 ella-sonika wpa_supplicant[4589]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="U+Net42F0_5G" Nov 23 16:38:14 ella-sonika wpa_supplicant[4589]: wlan0: Trying to associate with SSID 'U+Net42F0_5G' Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: carrier acquired Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: connected to Access Point `U+Net42F0_5G' Nov 23 16:38:14 ella-sonika wpa_supplicant[4589]: wlan0: Associated with 18:c5:01:de:42:f3 Nov 23 16:38:14 ella-sonika wpa_supplicant[4589]: wlan0: CTRL-EVENT-CONNECTED - Connection to 18:c5:01:de:42:f3 completed [id=0 id_str=] Nov 23 16:38:14 ella-sonika wpa_supplicant[4589]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: IAID dd:e1:a3:25 Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: adding address fe80::2465:5a5e:3380:1388 Nov 23 16:38:14 ella-sonika dhcpcd[4635]: ipv6_addaddr1: Permission denied Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: carrier lost Nov 23 16:38:14 ella-sonika systemd-udevd[4932]: Process '/sbin/crda' failed with exit code 234. Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: carrier acquired Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: IAID dd:e1:a3:25 Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: soliciting a DHCP lease Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: soliciting an IPv6 router Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: offered 192.168.219.123 from 192.168.219.1 Nov 23 16:38:14 ella-sonika dhcpcd[4635]: wlan0: probing address 192.168.219.123/24 Nov 23 16:38:15 ella-sonika kernel: hwmon hwmon2: Undervoltage detected! Nov 23 16:38:17 ella-sonika wpa_supplicant[4589]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 23 16:38:17 ella-sonika kernel: hwmon hwmon2: Voltage normalised Nov 23 16:38:17 ella-sonika sudo[4972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 16:38:17 ella-sonika sudo[4972]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:38:17 ella-sonika sudo[4972]: pam_unix(sudo:session): session closed for user root Nov 23 16:38:17 ella-sonika sudo[4975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 16:38:17 ella-sonika sudo[4975]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:38:17 ella-sonika sudo[4975]: pam_unix(sudo:session): session closed for user root Nov 23 16:38:17 ella-sonika volumio[1237]: verbose: New Socket.io Connection to 192.168.219.122 from 192.168.219.106 UA: Mozilla/5.0 (Linux; Android 14; SM-S901N Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 23 16:38:18 ella-sonika sudo[4978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 16:38:18 ella-sonika sudo[4978]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:38:18 ella-sonika sudo[4978]: pam_unix(sudo:session): session closed for user root Nov 23 16:38:18 ella-sonika sudo[4981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 16:38:18 ella-sonika sudo[4981]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 16:38:18 ella-sonika sudo[4981]: pam_unix(sudo:session): session closed for user root Nov 23 16:38:18 ella-sonika volumio[1237]: verbose: New Socket.io Connection to 192.168.219.122 from 192.168.219.106 UA: Mozilla/5.0 (Linux; Android 14; SM-S901N Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:18 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 16:38:18 ella-sonika volumio[1237]: info: Received Get System Info Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:18 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:18 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:18 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:18 ella-sonika volumio[1237]: info: Listing playlists Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 16:38:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 23 16:38:19 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 23 16:38:19 ella-sonika kernel: hwmon hwmon2: Undervoltage detected! Nov 23 16:38:19 ella-sonika dhcpcd[4635]: wlan0: leased 192.168.219.123 for 21600 seconds Nov 23 16:38:19 ella-sonika avahi-daemon[826]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.219.123. Nov 23 16:38:19 ella-sonika dhcpcd[4635]: wlan0: adding route to 192.168.219.0/24 Nov 23 16:38:19 ella-sonika dhcpcd[4635]: wlan0: adding default route via 192.168.219.1 Nov 23 16:38:19 ella-sonika avahi-daemon[826]: New relevant interface wlan0.IPv4 for mDNS. Nov 23 16:38:19 ella-sonika avahi-daemon[826]: Registering new address record for 192.168.219.123 on wlan0.IPv4. Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 16:38:20 ella-sonika volumio[1237]: info: Received Get System Info Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:20 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:20 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:20 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:20 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:20 ella-sonika volumio[1237]: info: Discovery: this is already registered, fbc8e613-005d-40b1-ad9d-1375ce2d6058 Nov 23 16:38:20 ella-sonika volumio[1237]: info: Discovery: Found device ella sonika Nov 23 16:38:20 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:20 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:21 ella-sonika ntpd[981]: Listen normally on 4 wlan0 192.168.219.123:123 Nov 23 16:38:21 ella-sonika ntpd[981]: new interface(s) found: waking up resolver Nov 23 16:38:21 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 16:38:21 ella-sonika volumio[1237]: info: Received Get System Info Nov 23 16:38:21 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:21 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:21 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:21 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:21 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:21 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:21 ella-sonika kernel: hwmon hwmon2: Voltage normalised Nov 23 16:38:22 ella-sonika volumio[1237]: info: Volumio Network Manager: Network status updated: 3 Nov 23 16:38:28 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 16:38:33 ella-sonika volumiologrotate[790]: ls: cannot access '/var/log/samba/log.wb-ELLA': No such file or directory Nov 23 16:38:33 ella-sonika volumiologrotate[790]: ls: cannot access 'SONIKA': No such file or directory Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 16:38:37 ella-sonika volumio[1237]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 23 16:38:37 ella-sonika volumio[1237]: info: Received Get System Version Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 16:38:37 ella-sonika volumio[1237]: info: Received Get System Info Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:37 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:37 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:37 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:38:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:38:50 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:38:50 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:38:50 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:38:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:38:52 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetQueue Nov 23 16:38:52 ella-sonika volumio[1237]: info: CoreStateMachine::getQueue Nov 23 16:38:52 ella-sonika volumio[1237]: info: CorePlayQueue::getQueue Nov 23 16:39:06 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 16:39:06 ella-sonika volumio[1237]: info: Preload queue cleared Nov 23 16:39:18 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 16:39:18 ella-sonika volumio[1237]: info: Preload queue cleared Nov 23 16:39:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:39:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:39:20 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:39:20 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:39:20 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:39:20 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:39:29 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Nov 23 16:39:29 ella-sonika volumio[1237]: Error Retrieving last played file: Error: /data/laststates.json: ENOENT: no such file or directory, open '/data/laststates.json' Nov 23 16:39:29 ella-sonika volumio[1237]: info: Preload queue cleared Nov 23 16:39:31 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 16:39:31 ella-sonika volumio[1237]: info: CURURI: music-library Nov 23 16:39:31 ella-sonika volumio[1237]: error: Failed LSINFO: null Nov 23 16:39:32 ella-sonika volumio[1237]: info: Preload queue cleared Nov 23 16:39:33 ella-sonika volumiologrotate[790]: ls: cannot access '/var/log/samba/log.wb-ELLA': No such file or directory Nov 23 16:39:33 ella-sonika volumiologrotate[790]: ls: cannot access 'SONIKA': No such file or directory Nov 23 16:39:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 16:39:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 16:39:50 ella-sonika volumio[1237]: info: Discovery: Getting this device information Nov 23 16:39:50 ella-sonika volumio[1237]: info: CoreCommandRouter::volumioGetState Nov 23 16:39:50 ella-sonika volumio[1237]: info: CorePlayQueue::getTrack 0 Nov 23 16:39:50 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 16:39:58 ella-sonika volumio[1237]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Nov 23 16:39:58 ella-sonika volumio[1237]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:39:58 ella-sonika volumio[1237]: TypeError: Cannot read property 'then' of undefined Nov 23 16:39:58 ella-sonika volumio[1237]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Nov 23 16:39:58 ella-sonika volumio[1237]: at Socket.emit (events.js:315:20) Nov 23 16:39:58 ella-sonika volumio[1237]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 23 16:39:58 ella-sonika volumio[1237]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 23 16:39:58 ella-sonika volumio[1237]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 16:39:58 ella-sonika sudo[5272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 16:38 Nov 23 16:39:58 ella-sonika sudo[5272]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"