-- Logs begin at Fri 2024-09-06 20:50:01 IST, end at Fri 2024-09-06 22:18:16 IST. -- Sep 06 22:17:00 volumio dhcpcd[998]: wlan0: carrier lost Sep 06 22:17:00 volumio avahi-daemon[795]: Withdrawing address record for 192.168.1.72 on wlan0. Sep 06 22:17:00 volumio avahi-daemon[795]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.72. Sep 06 22:17:00 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-DISCONNECTED bssid=64:fb:92:96:16:d7 reason=4 locally_generated=1 Sep 06 22:17:00 volumio avahi-daemon[795]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 06 22:17:00 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 06 22:17:00 volumio dhcpcd[998]: wlan0: deleting route to 192.168.1.0/24 Sep 06 22:17:00 volumio dhcpcd[998]: wlan0: deleting default route via 192.168.1.1 Sep 06 22:17:00 volumio volumio[1381]: info: Discovery: A device disappeared from network Sep 06 22:17:00 volumio volumio[1381]: info: Discovery: Device volumio disappeared from network Sep 06 22:17:01 volumio ntpd[14595]: Deleting interface #4 wlan0, 192.168.1.72#123, interface stats: received=0, sent=0, dropped=0, active_time=52 secs Sep 06 22:17:01 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:01 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:01 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:01 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:02 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 3/3) Sep 06 22:17:02 volumio wpa_supplicant[8590]: wlan0: Trying to associate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:02 volumio kernel: wlan0: authenticated Sep 06 22:17:02 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:02 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:02 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 3/3) Sep 06 22:17:02 volumio kernel: wlan0: association with 64:fb:92:96:16:d7 timed out Sep 06 22:17:04 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:04 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:04 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:04 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:04 volumio wpa_supplicant[8590]: wlan0: Trying to associate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:04 volumio kernel: wlan0: authenticated Sep 06 22:17:04 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:04 volumio wpa_supplicant[8590]: wlan0: Associated with 64:fb:92:96:16:d7 Sep 06 22:17:04 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 06 22:17:04 volumio kernel: wlan0: RX AssocResp from 64:fb:92:96:16:d7 (capab=0x411 status=0 aid=6) Sep 06 22:17:04 volumio kernel: wlan0: associated Sep 06 22:17:06 volumio wpa_supplicant[8590]: wlan0: WPA: Key negotiation completed with 64:fb:92:96:16:d7 [PTK=CCMP GTK=CCMP] Sep 06 22:17:06 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-CONNECTED - Connection to 64:fb:92:96:16:d7 completed [id=0 id_str=] Sep 06 22:17:06 volumio dhcpcd[998]: wlan0: carrier acquired Sep 06 22:17:06 volumio dhcpcd[998]: wlan0: IAID 47:21:f6:15 Sep 06 22:17:06 volumio dhcpcd[998]: wlan0: rebinding lease of 192.168.1.72 Sep 06 22:17:06 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:06 volumio dhcpcd[998]: wlan0: soliciting an IPv6 router Sep 06 22:17:11 volumio dhcpcd[998]: wlan0: probing for an IPv4LL address Sep 06 22:17:11 volumio dhcpcd[998]: wlan0: DHCP lease expired Sep 06 22:17:11 volumio dhcpcd[998]: wlan0: soliciting a DHCP lease Sep 06 22:17:12 volumio dhcpcd[998]: wlan0: offered 192.168.1.72 from 192.168.1.1 Sep 06 22:17:12 volumio dhcpcd[998]: wlan0: probing address 192.168.1.72/24 Sep 06 22:17:15 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:16 volumio dhcpcd[998]: wlan0: using IPv4LL address 169.254.96.27 Sep 06 22:17:16 volumio avahi-daemon[795]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.96.27. Sep 06 22:17:16 volumio avahi-daemon[795]: New relevant interface wlan0.IPv4 for mDNS. Sep 06 22:17:16 volumio dhcpcd[998]: wlan0: adding route to 169.254.0.0/16 Sep 06 22:17:16 volumio avahi-daemon[795]: Registering new address record for 169.254.96.27 on wlan0.IPv4. Sep 06 22:17:16 volumio volumio[1381]: info: Discovery: adding 5f203c6f-4512-4efa-a9f3-2d29d3c782d6 Sep 06 22:17:16 volumio volumio[1381]: info: Discovery: Found device Volumio Sep 06 22:17:16 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:16 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:17 volumio dhcpcd[998]: wlan0: leased 192.168.1.72 for 86400 seconds Sep 06 22:17:17 volumio avahi-daemon[795]: Registering new address record for 192.168.1.72 on wlan0.IPv4. Sep 06 22:17:17 volumio dhcpcd[998]: wlan0: adding route to 192.168.1.0/24 Sep 06 22:17:17 volumio dhcpcd[998]: wlan0: adding default route via 192.168.1.1 Sep 06 22:17:17 volumio ntpd[14595]: ntpd exiting on signal 15 (Terminated) Sep 06 22:17:17 volumio ntpd[14595]: 95.216.192.15 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio systemd[1]: Stopping Network Time Service... Sep 06 22:17:17 volumio ntpd[14595]: 194.0.5.123 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 162.159.200.1 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 192.46.211.253 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 17.253.18.99 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 192.46.215.141 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 139.84.142.141 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 172.105.60.167 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 192.46.210.39 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 14.139.60.106 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 172.232.97.196 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 162.159.200.123 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 65.0.119.56 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 192.46.215.60 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio ntpd[14595]: 157.245.102.2 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio systemd[1]: ntp.service: Succeeded. Sep 06 22:17:17 volumio ntpd[14595]: 15.207.248.194 local addr 192.168.1.76 -> Sep 06 22:17:17 volumio systemd[1]: Stopped Network Time Service. Sep 06 22:17:17 volumio systemd[1]: Starting Network Time Service... Sep 06 22:17:17 volumio ntpd[14770]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 06 22:17:17 volumio ntpd[14770]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 06 22:17:17 volumio systemd[1]: Started Network Time Service. Sep 06 22:17:17 volumio ntpd[14776]: proto: precision = 0.087 usec (-23) Sep 06 22:17:17 volumio ntpd[14776]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 06 22:17:17 volumio avahi-daemon[795]: Withdrawing address record for 169.254.96.27 on wlan0. Sep 06 22:17:17 volumio ntpd[14776]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 06 22:17:17 volumio avahi-daemon[795]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.96.27. Sep 06 22:17:17 volumio ntpd[14776]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 619 days ago Sep 06 22:17:17 volumio ntpd[14776]: Listen and drop on 0 v6wildcard [::]:123 Sep 06 22:17:17 volumio ntpd[14776]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 06 22:17:17 volumio dhcpcd[998]: wlan0: deleting route to 169.254.0.0/16 Sep 06 22:17:17 volumio avahi-daemon[795]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.72. Sep 06 22:17:17 volumio ntpd[14776]: Listen normally on 2 lo 127.0.0.1:123 Sep 06 22:17:17 volumio ntpd[14776]: Listen normally on 3 eth0 192.168.1.76:123 Sep 06 22:17:17 volumio ntpd[14776]: Listen normally on 4 wlan0 192.168.1.72:123 Sep 06 22:17:17 volumio ntpd[14776]: Listening on routing socket on fd #21 for interface updates Sep 06 22:17:17 volumio ntpd[14776]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 06 22:17:17 volumio ntpd[14776]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 06 22:17:18 volumio ntpd[14776]: Soliciting pool server 162.159.200.123 Sep 06 22:17:18 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:19 volumio ntpd[14776]: Soliciting pool server 192.46.215.60 Sep 06 22:17:19 volumio ntpd[14776]: Soliciting pool server 162.159.200.1 Sep 06 22:17:19 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:20 volumio ntpd[14776]: Soliciting pool server 192.46.211.253 Sep 06 22:17:20 volumio ntpd[14776]: Soliciting pool server 139.84.142.141 Sep 06 22:17:20 volumio ntpd[14776]: Soliciting pool server 192.46.215.141 Sep 06 22:17:21 volumio ntpd[14776]: Soliciting pool server 14.139.60.106 Sep 06 22:17:21 volumio ntpd[14776]: Soliciting pool server 192.46.210.39 Sep 06 22:17:21 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:21 volumio ntpd[14776]: Soliciting pool server 95.216.144.226 Sep 06 22:17:22 volumio ntpd[14776]: Soliciting pool server 65.0.119.56 Sep 06 22:17:22 volumio ntpd[14776]: Soliciting pool server 172.232.97.196 Sep 06 22:17:22 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:22 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:22 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:22 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:22 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:22 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:22 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:22 volumio volumio[1381]: verbose: New Socket.io Connection to 192.168.1.76:3000 from 192.168.1.74 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:22 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 06 22:17:22 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 06 22:17:23 volumio ntpd[14776]: Soliciting pool server 157.245.102.2 Sep 06 22:17:24 volumio ntpd[14776]: Soliciting pool server 2600:3c08:e001:8:0:a789:b456:c123 Sep 06 22:17:25 volumio sudo[14793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 22:17:25 volumio sudo[14793]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:25 volumio sudo[14793]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:25 volumio sudo[14796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 22:17:25 volumio sudo[14796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:25 volumio sudo[14796]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:26 volumio volumio[1381]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.74 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Sep 06 22:17:26 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 22:17:26 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 06 22:17:26 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:26 volumio sudo[14799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 22:17:26 volumio sudo[14799]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:26 volumio sudo[14799]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:26 volumio sudo[14802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 22:17:26 volumio sudo[14802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:26 volumio sudo[14802]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:26 volumio volumio[1381]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.74 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 06 22:17:26 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:27 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 22:17:27 volumio volumio[1381]: info: Received Get System Info Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:27 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:27 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:27 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:27 volumio volumio[1381]: info: Listing playlists Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 22:17:27 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 06 22:17:27 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 22:17:28 volumio volumio[1381]: info: Received Get System Info Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:28 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:28 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 22:17:28 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 06 22:17:29 volumio volumio[1381]: info: CoreCommandRouter::volumioPlay Sep 06 22:17:29 volumio volumio[1381]: info: CoreStateMachine::play index undefined Sep 06 22:17:29 volumio volumio[1381]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 22:17:29 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:29 volumio volumio[1381]: info: CoreStateMachine::startPlaybackTimer Sep 06 22:17:29 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:29 volumio volumio[1381]: info: ControllerMpd::resume Sep 06 22:17:29 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand play Sep 06 22:17:30 volumio volumio[1381]: info: Sep 06 22:17:30 volumio volumio[1381]: ---------------------------- MPD announces state update: player Sep 06 22:17:30 volumio volumio[1381]: info: sendMpdCommand play took 404 milliseconds Sep 06 22:17:30 volumio volumio[1381]: info: ControllerMpd::getState Sep 06 22:17:30 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand status Sep 06 22:17:30 volumio volumio[1381]: info: sendMpdCommand status took 1 milliseconds Sep 06 22:17:30 volumio volumio[1381]: verbose: ControllerMpd::parseState Sep 06 22:17:30 volumio volumio[1381]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 22:17:30 volumio volumio[1381]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 06 22:17:30 volumio volumio[1381]: verbose: ControllerMpd::parseTrackInfo Sep 06 22:17:30 volumio volumio[1381]: info: ControllerMpd::pushState Sep 06 22:17:30 volumio volumio[1381]: info: CoreCommandRouter::servicePushState Sep 06 22:17:30 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:30 volumio volumio[1381]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53341,"duration":236,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album (11/16/2021 1:16:54 AM)","uri":"USB/Training_vedio/Dhwani (1988) FLAC/Anuraagalola.wav","trackType":"wav"} Sep 06 22:17:30 volumio volumio[1381]: verbose: CURRENT POSITION 0 Sep 06 22:17:30 volumio volumio[1381]: info: CoreStateMachine::syncState stateService play Sep 06 22:17:30 volumio volumio[1381]: info: CoreStateMachine::syncState currentStatus pause Sep 06 22:17:30 volumio volumio[1381]: info: CoreStateMachine::pushState Sep 06 22:17:30 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:30 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 22:17:30 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Sep 06 22:17:30 volumio volumio[1381]: info: ------------------------------ 4ms Sep 06 22:17:30 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:33 volumio dhcpcd[998]: wlan0: carrier lost Sep 06 22:17:33 volumio avahi-daemon[795]: Withdrawing address record for 192.168.1.72 on wlan0. Sep 06 22:17:33 volumio avahi-daemon[795]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.72. Sep 06 22:17:33 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-DISCONNECTED bssid=64:fb:92:96:16:d7 reason=4 locally_generated=1 Sep 06 22:17:33 volumio avahi-daemon[795]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 06 22:17:33 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 06 22:17:33 volumio volumio[1381]: info: Discovery: A device disappeared from network Sep 06 22:17:33 volumio volumio[1381]: info: Discovery: Device volumio disappeared from network Sep 06 22:17:33 volumio dhcpcd[998]: wlan0: deleting route to 192.168.1.0/24 Sep 06 22:17:33 volumio dhcpcd[998]: wlan0: deleting default route via 192.168.1.1 Sep 06 22:17:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 22:17:33 volumio volumio[1381]: info: Received Get System Info Sep 06 22:17:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:33 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:33 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:33 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:33 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:34 volumio ntpd[14776]: Deleting interface #4 wlan0, 192.168.1.72#123, interface stats: received=0, sent=0, dropped=0, active_time=17 secs Sep 06 22:17:34 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:34 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:34 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:34 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:34 volumio kernel: wlan0: authenticated Sep 06 22:17:34 volumio wpa_supplicant[8590]: wlan0: Trying to associate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:34 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:35 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:35 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 3/3) Sep 06 22:17:35 volumio kernel: wlan0: association with 64:fb:92:96:16:d7 timed out Sep 06 22:17:36 volumio nmbd[876]: [2024/09/06 22:17:36.421255, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 06 22:17:36 volumio nmbd[876]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Sep 06 22:17:36 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 06 22:17:37 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:37 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:37 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:37 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:37 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 3/3) Sep 06 22:17:38 volumio kernel: wlan0: authentication with 64:fb:92:96:16:d7 timed out Sep 06 22:17:40 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:40 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:40 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:40 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 2/3) Sep 06 22:17:41 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 3/3) Sep 06 22:17:41 volumio kernel: wlan0: authentication with 64:fb:92:96:16:d7 timed out Sep 06 22:17:41 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="STAR VISION" auth_failures=1 duration=10 reason=CONN_FAILED Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 22:17:45 volumio volumio[1381]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 22:17:45 volumio volumio[1381]: info: Received Get System Version Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 22:17:45 volumio volumio[1381]: info: Received Get System Info Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:45 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:45 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:45 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:47 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 22:17:47 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 22:17:47 volumio volumio[1381]: info: Discovery: Getting this device information Sep 06 22:17:47 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:47 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:47 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:49 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 22:17:49 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 22:17:50 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="STAR VISION" Sep 06 22:17:50 volumio kernel: wlan0: authenticate with 64:fb:92:96:16:d7 Sep 06 22:17:50 volumio wpa_supplicant[8590]: wlan0: SME: Trying to authenticate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:50 volumio kernel: wlan0: send auth to 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:50 volumio wpa_supplicant[8590]: wlan0: Trying to associate with 64:fb:92:96:16:d7 (SSID='STAR VISION' freq=2462 MHz) Sep 06 22:17:50 volumio kernel: wlan0: authenticated Sep 06 22:17:50 volumio kernel: wlan0: associate with 64:fb:92:96:16:d7 (try 1/3) Sep 06 22:17:50 volumio wpa_supplicant[8590]: wlan0: Associated with 64:fb:92:96:16:d7 Sep 06 22:17:50 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 06 22:17:50 volumio kernel: wlan0: RX AssocResp from 64:fb:92:96:16:d7 (capab=0x411 status=0 aid=6) Sep 06 22:17:50 volumio kernel: wlan0: associated Sep 06 22:17:51 volumio wpa_supplicant[8590]: wlan0: WPA: Key negotiation completed with 64:fb:92:96:16:d7 [PTK=CCMP GTK=CCMP] Sep 06 22:17:51 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-CONNECTED - Connection to 64:fb:92:96:16:d7 completed [id=0 id_str=] Sep 06 22:17:51 volumio dhcpcd[998]: wlan0: carrier acquired Sep 06 22:17:51 volumio dhcpcd[998]: wlan0: IAID 47:21:f6:15 Sep 06 22:17:52 volumio dhcpcd[998]: wlan0: soliciting an IPv6 router Sep 06 22:17:52 volumio dhcpcd[998]: wlan0: rebinding lease of 192.168.1.72 Sep 06 22:17:53 volumio dhcpcd[998]: wlan0: probing address 192.168.1.72/24 Sep 06 22:17:55 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:55 volumio volumio[1381]: info: Enabling plugin fusiondsp Sep 06 22:17:55 volumio volumio[1381]: info: Loading plugin "fusiondsp"... Sep 06 22:17:56 volumio volumio[1381]: info: Preparing to generate the ALSA configuration file Sep 06 22:17:56 volumio volumio[1381]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 22:17:56 volumio volumio[1381]: info: Reading ALSA contributions from plugins. Sep 06 22:17:56 volumio volumio[1381]: info: Asound.conf file written Sep 06 22:17:56 volumio sudo[14839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 06 22:17:56 volumio sudo[14839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio sudo[14839]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:17:56 volumio volumio[1381]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Sep 06 22:17:56 volumio volumio[1381]: Hardware is initialized using a generic method Sep 06 22:17:56 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:17:56 volumio volumio[1381]: info: Output device has changed, restarting MPD Sep 06 22:17:56 volumio sudo[14844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 22:17:56 volumio sudo[14844]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio sudo[14844]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: info: Output device has changed, restarting Shairport Sync Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:17:56 volumio sudo[14847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 22:17:56 volumio sudo[14847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio systemd[1]: Stopping Music Player Daemon... Sep 06 22:17:56 volumio volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 22:17:56 volumio volumio[1381]: info: PLUGIN START: fusiondsp Sep 06 22:17:56 volumio volumio[1381]: info: Loading i18n strings for locale en Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: FusionDsp - mixtype--------------------- None Sep 06 22:17:56 volumio volumio[1381]: info: Preparing to generate the ALSA configuration file Sep 06 22:17:56 volumio volumio[1381]: info: Done. Sep 06 22:17:56 volumio volumio[1381]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 22:17:56 volumio volumio[1381]: info: Reading ALSA contributions from plugins. Sep 06 22:17:56 volumio volumio[1381]: info: MPD Permissions set Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:17:56 volumio volumio[1381]: info: Starting Shairport Sync Sep 06 22:17:56 volumio volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Sep 06 22:17:56 volumio volumio[1381]: info: Output device has changed, restarting MPD Sep 06 22:17:56 volumio sudo[14859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 22:17:56 volumio sudo[14859]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 22:17:56 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 06 22:17:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 22:17:56 volumio sudo[14862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 22:17:56 volumio sudo[14862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio sudo[14862]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: info: Output device has changed, restarting Shairport Sync Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 22:17:56 volumio sudo[14866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 22:17:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 22:17:56 volumio sudo[14866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio sudo[14859]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio systemd[1]: mpd.service: Succeeded. Sep 06 22:17:56 volumio systemd[1]: Stopped Music Player Daemon. Sep 06 22:17:56 volumio systemd[1]: Starting Music Player Daemon... Sep 06 22:17:56 volumio volumio[1381]: info: MPD Permissions set Sep 06 22:17:56 volumio volumio[1381]: info: Shairport-Sync Started Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio sudo[14873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 22:17:56 volumio sudo[14873]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio sudo[14873]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 22:17:56 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:56 volumio volumio[1381]: info: Starting Shairport Sync Sep 06 22:17:56 volumio sudo[14883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 22:17:56 volumio sudo[14883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 22:17:56 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 06 22:17:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 22:17:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 22:17:56 volumio sudo[14883]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: info: Shairport-Sync Started Sep 06 22:17:56 volumio mpd[14879]: Sep 06 22:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 22:17:56 volumio systemd[1]: Started Music Player Daemon. Sep 06 22:17:56 volumio sudo[14866]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio sudo[14847]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:56 volumio volumio[1381]: error: MPD error: The expression evaluated to a falsy value: Sep 06 22:17:56 volumio volumio[1381]: assert.ok(self.idling) Sep 06 22:17:56 volumio volumio[1381]: error: The expression evaluated to a falsy value: Sep 06 22:17:56 volumio volumio[1381]: assert.ok(self.idling) Sep 06 22:17:56 volumio volumio[1381]: error: updateQueue error: null Sep 06 22:17:57 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:17:58 volumio volumio[1381]: info: camilladsp spawned new process with pid 14897, instance 1, run: true Sep 06 22:17:58 volumio volumio[1381]: info: camilladsp service started and running in background, instance 1 Sep 06 22:17:58 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:17:58 volumio volumio[1381]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Sep 06 22:17:58 volumio volumio[1381]: info: FusionDsp loaded Sep 06 22:17:58 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 22:17:58 volumio sudo[14903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 06 22:17:58 volumio sudo[14903]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:17:58 volumio volumio[1381]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 06 22:17:58 volumio volumio[1381]: info: Adding Signal Path Element [object Object] Sep 06 22:17:58 volumio volumio[1381]: info: Adding fusiondspeq DSP Signal Path Element Sep 06 22:17:58 volumio volumio[1381]: info: FusionDsp - ---- installed callbackRead Sep 06 22:17:58 volumio volumio[1381]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 22:17:58 volumio volumio[1381]: error: FusionDsp - WebSocket error: [object Object] Sep 06 22:17:58 volumio volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 06 22:17:58 volumio systemd[1]: Started FusionDsp Daemon. Sep 06 22:17:58 volumio sudo[14903]: pam_unix(sudo:session): session closed for user root Sep 06 22:17:58 volumio dhcpcd[998]: wlan0: leased 192.168.1.72 for 86400 seconds Sep 06 22:17:58 volumio avahi-daemon[795]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.72. Sep 06 22:17:58 volumio dhcpcd[998]: wlan0: adding route to 192.168.1.0/24 Sep 06 22:17:58 volumio avahi-daemon[795]: New relevant interface wlan0.IPv4 for mDNS. Sep 06 22:17:58 volumio dhcpcd[998]: wlan0: adding default route via 192.168.1.1 Sep 06 22:17:58 volumio avahi-daemon[795]: Registering new address record for 192.168.1.72 on wlan0.IPv4. Sep 06 22:17:58 volumio ntpd[14776]: ntpd exiting on signal 15 (Terminated) Sep 06 22:17:58 volumio ntpd[14776]: 162.159.200.123 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio systemd[1]: Stopping Network Time Service... Sep 06 22:17:58 volumio ntpd[14776]: 192.46.215.60 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 162.159.200.1 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 139.84.142.141 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 192.46.211.253 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 192.46.215.141 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 192.46.210.39 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 14.139.60.106 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 172.232.97.196 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 65.0.119.56 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio ntpd[14776]: 157.245.102.2 local addr 192.168.1.76 -> Sep 06 22:17:58 volumio systemd[1]: ntp.service: Succeeded. Sep 06 22:17:58 volumio systemd[1]: Stopped Network Time Service. Sep 06 22:17:58 volumio systemd[1]: Starting Network Time Service... Sep 06 22:17:58 volumio ntpd[14938]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 06 22:17:58 volumio ntpd[14938]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 06 22:17:58 volumio systemd[1]: Started Network Time Service. Sep 06 22:17:58 volumio ntpd[14944]: proto: precision = 0.108 usec (-23) Sep 06 22:17:58 volumio ntpd[14944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 06 22:17:58 volumio ntpd[14944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 06 22:17:58 volumio ntpd[14944]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 619 days ago Sep 06 22:17:58 volumio ntpd[14944]: Listen and drop on 0 v6wildcard [::]:123 Sep 06 22:17:58 volumio ntpd[14944]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 06 22:17:58 volumio ntpd[14944]: Listen normally on 2 lo 127.0.0.1:123 Sep 06 22:17:58 volumio ntpd[14944]: Listen normally on 3 eth0 192.168.1.76:123 Sep 06 22:17:58 volumio ntpd[14944]: Listen normally on 4 wlan0 192.168.1.72:123 Sep 06 22:17:58 volumio ntpd[14944]: Listening on routing socket on fd #21 for interface updates Sep 06 22:17:58 volumio ntpd[14944]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 06 22:17:58 volumio ntpd[14944]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 06 22:17:59 volumio volumio[1381]: 0+0 records in Sep 06 22:17:59 volumio volumio[1381]: 0+0 records out Sep 06 22:17:59 volumio volumio[1381]: 0 bytes copied, 5.05e-05 s, 0.0 kB/s Sep 06 22:17:59 volumio volumio[1381]: info: camilladsp spawned new process with pid 14949, instance 1, run: true Sep 06 22:17:59 volumio volumio[1381]: info: Discovery: adding 5f203c6f-4512-4efa-a9f3-2d29d3c782d6 Sep 06 22:17:59 volumio volumio[1381]: info: Discovery: Found device Volumio Sep 06 22:17:59 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:17:59 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:17:59 volumio ntpd[14944]: Soliciting pool server 192.46.211.253 Sep 06 22:18:00 volumio ntpd[14944]: Soliciting pool server 162.159.200.123 Sep 06 22:18:00 volumio ntpd[14944]: Soliciting pool server 172.105.60.167 Sep 06 22:18:01 volumio volumio[1381]: info: Enabling plugin RoonBridge Sep 06 22:18:01 volumio volumio[1381]: info: Loading plugin "RoonBridge"... Sep 06 22:18:01 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:01 volumio volumio[1381]: info: PLUGIN START: RoonBridge Sep 06 22:18:01 volumio ntpd[14944]: Soliciting pool server 95.216.192.15 Sep 06 22:18:01 volumio ntpd[14944]: Soliciting pool server 192.46.215.60 Sep 06 22:18:01 volumio ntpd[14944]: Soliciting pool server 157.245.102.2 Sep 06 22:18:01 volumio sudo[14957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 06 22:18:01 volumio sudo[14957]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:01 volumio systemd[1]: Started RoonBridge. Sep 06 22:18:01 volumio sudo[14957]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:01 volumio volumio[1381]: info: Done. Sep 06 22:18:01 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:18:01 volumio RoonBridge[14959]: 00:00:00.001 Warn: get lock file path: /tmp/.rnbgem0- Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.095 Trace: [childprocess] using unix child process Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.110 Debug: PathForResource, filename: ../.update Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.114 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.116 Debug: PathForResource, filename: RAATServer Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.116 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.116 Debug: PathForResource, filename: RoonBridgeHelper Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.116 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 06 22:18:02 volumio RoonBridge[14959]: Initializing Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.127 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.133 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 06 22:18:02 volumio RoonBridge[14959]: Not Running (.o) Sep 06 22:18:02 volumio RoonBridge[14959]: Running Sep 06 22:18:02 volumio RoonBridge[14959]: 00:00:00.006 Warn: get lock file path: /tmp/.rnbhgem0- Sep 06 22:18:02 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:02 volumio ntpd[14944]: Soliciting pool server 192.46.215.141 Sep 06 22:18:02 volumio ntpd[14944]: Soliciting pool server 162.159.200.1 Sep 06 22:18:02 volumio ntpd[14944]: Soliciting pool server 139.84.142.141 Sep 06 22:18:02 volumio ntpd[14944]: Soliciting pool server 192.46.215.141 Sep 06 22:18:03 volumio ntpd[14944]: Soliciting pool server 14.139.60.106 Sep 06 22:18:03 volumio ntpd[14944]: Soliciting pool server 192.46.210.39 Sep 06 22:18:04 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:04 volumio ntpd[14944]: Soliciting pool server 65.0.119.56 Sep 06 22:18:04 volumio volumio[1381]: info: Enabling plugin ytcr Sep 06 22:18:04 volumio volumio[1381]: info: Loading plugin "ytcr"... Sep 06 22:18:05 volumio volumio[1381]: info: PLUGIN START: ytcr Sep 06 22:18:05 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:05 volumio ntpd[14944]: Soliciting pool server 2401:c080:2400:1c19:5400:4ff:fe0b:be05 Sep 06 22:18:06 volumio volumio[1381]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 06 22:18:06 volumio volumio[1381]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 22:18:06 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:18:06 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:18:06 volumio volumio[1381]: info: CoreStateMachine::pushState Sep 06 22:18:06 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:18:06 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 22:18:06 volumio volumio[1381]: info: CoreCommandRouter::volumioPushState Sep 06 22:18:06 volumio volumio[1381]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Sep 06 22:18:06 volumio volumio[1381]: (TypeError) Cannot read property 'vol' of undefined Sep 06 22:18:06 volumio volumio[1381]: TypeError: Cannot read property 'vol' of undefined Sep 06 22:18:06 volumio volumio[1381]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Sep 06 22:18:06 volumio volumio[1381]: at runMicrotasks () Sep 06 22:18:06 volumio volumio[1381]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Sep 06 22:18:06 volumio volumio[1381]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Sep 06 22:18:06 volumio volumio[1381]: at async /data/plugins/music_service/ytcr/dist/index.js:312:13 Sep 06 22:18:06 volumio volumio[1381]: info: Done. Sep 06 22:18:06 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:18:08 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:09 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:10 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:11 volumio volumio[1381]: info: Enabling plugin autostart Sep 06 22:18:11 volumio volumio[1381]: info: Loading plugin "autostart"... Sep 06 22:18:11 volumio volumio[1381]: info: AutoStart - onVolumioStart - read config.json Sep 06 22:18:11 volumio volumio[1381]: info: PLUGIN START: autostart Sep 06 22:18:11 volumio volumio[1381]: info: AutoStart - onStart Sep 06 22:18:11 volumio volumio[1381]: info: Done. Sep 06 22:18:11 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:18:12 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:13 volumio volumio[1381]: info: Enabling plugin peppymeterbasic Sep 06 22:18:13 volumio volumio[1381]: info: Loading plugin "peppymeterbasic"... Sep 06 22:18:13 volumio volumio[1381]: info: Preparing to generate the ALSA configuration file Sep 06 22:18:13 volumio volumio[1381]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 22:18:13 volumio volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 06 22:18:13 volumio volumio[1381]: info: Reading ALSA contributions from plugins. Sep 06 22:18:13 volumio volumio[1381]: info: Asound.conf file written Sep 06 22:18:13 volumio sudo[15089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 06 22:18:13 volumio sudo[15089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio sudo[15089]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:18:13 volumio volumio[1381]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Sep 06 22:18:13 volumio volumio[1381]: Hardware is initialized using a generic method Sep 06 22:18:13 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:18:13 volumio volumio[1381]: info: Output device has changed, restarting MPD Sep 06 22:18:13 volumio sudo[15094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 22:18:13 volumio sudo[15094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio sudo[15094]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: info: Output device has changed, restarting Shairport Sync Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:13 volumio sudo[15097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 22:18:13 volumio sudo[15097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio systemd[1]: Stopping Music Player Daemon... Sep 06 22:18:13 volumio volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 22:18:13 volumio volumio[1381]: info: PLUGIN START: peppymeterbasic Sep 06 22:18:13 volumio systemd[1]: mpd.service: Succeeded. Sep 06 22:18:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 06 22:18:13 volumio wpa_supplicant[8590]: wlan0: CTRL-EVENT-BEACON-LOSS Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 06 22:18:13 volumio volumio[1381]: info: Preparing to generate the ALSA configuration file Sep 06 22:18:13 volumio systemd[1]: Starting Music Player Daemon... Sep 06 22:18:13 volumio volumio[1381]: info: Done. Sep 06 22:18:13 volumio volumio[1381]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 22:18:13 volumio volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 06 22:18:13 volumio volumio[1381]: info: Reading ALSA contributions from plugins. Sep 06 22:18:13 volumio volumio[1381]: info: MPD Permissions set Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:18:13 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:18:13 volumio volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 22:18:13 volumio sudo[15102]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 22:18:13 volumio sudo[15102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio sudo[15102]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: info: Starting Shairport Sync Sep 06 22:18:13 volumio sudo[15112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 22:18:13 volumio sudo[15112]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Sep 06 22:18:13 volumio volumio[1381]: info: Output device has changed, restarting MPD Sep 06 22:18:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 22:18:13 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 06 22:18:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:13 volumio sudo[15115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 22:18:13 volumio sudo[15115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio sudo[15112]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio sudo[15115]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: info: Output device has changed, restarting Shairport Sync Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:13 volumio sudo[15119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 22:18:13 volumio sudo[15119]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 06 22:18:13 volumio systemd[1]: mpd.service: Succeeded. Sep 06 22:18:13 volumio systemd[1]: Stopped Music Player Daemon. Sep 06 22:18:13 volumio systemd[1]: Starting Music Player Daemon... Sep 06 22:18:13 volumio volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:18:13 volumio volumio[1381]: info: MPD Permissions set Sep 06 22:18:13 volumio volumio[1381]: info: Shairport-Sync Started Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio sudo[15126]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 22:18:13 volumio sudo[15126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio sudo[15126]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 22:18:13 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:13 volumio volumio[1381]: info: Starting Shairport Sync Sep 06 22:18:13 volumio sudo[15140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 22:18:13 volumio sudo[15140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 22:18:13 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 06 22:18:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:13 volumio sudo[15140]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: info: Shairport-Sync Started Sep 06 22:18:13 volumio mpd[15136]: Sep 06 22:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 22:18:13 volumio systemd[1]: Started Music Player Daemon. Sep 06 22:18:13 volumio sudo[15119]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio sudo[15097]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:13 volumio volumio[1381]: error: MPD error: The expression evaluated to a falsy value: Sep 06 22:18:13 volumio volumio[1381]: assert.ok(self.idling) Sep 06 22:18:13 volumio volumio[1381]: error: The expression evaluated to a falsy value: Sep 06 22:18:13 volumio volumio[1381]: assert.ok(self.idling) Sep 06 22:18:13 volumio volumio[1381]: error: updateQueue error: null Sep 06 22:18:15 volumio volumio[1381]: info: Enabling plugin peppyspectrum Sep 06 22:18:15 volumio volumio[1381]: info: Loading plugin "peppyspectrum"... Sep 06 22:18:15 volumio volumio[1381]: error: !!!! WARNING !!!! Sep 06 22:18:15 volumio volumio[1381]: error: The plugin user_interface/peppyspectrum failed to load, setting it to stopped. Error: Error: Cannot find module 'universalify' Sep 06 22:18:15 volumio volumio[1381]: Require stack: Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/fs/index.js Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/index.js Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/index.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/app/pluginmanager.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/app/index.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/index.js Sep 06 22:18:15 volumio volumio[1381]: error: Stack trace: Error: Cannot find module 'universalify' Sep 06 22:18:15 volumio volumio[1381]: Require stack: Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/fs/index.js Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/index.js Sep 06 22:18:15 volumio volumio[1381]: - /data/plugins/user_interface/peppyspectrum/index.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/app/pluginmanager.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/app/index.js Sep 06 22:18:15 volumio volumio[1381]: - /volumio/index.js Sep 06 22:18:15 volumio volumio[1381]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Sep 06 22:18:15 volumio volumio[1381]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Sep 06 22:18:15 volumio volumio[1381]: at Module.require (internal/modules/cjs/loader.js:1003:19) Sep 06 22:18:15 volumio volumio[1381]: at require (internal/modules/cjs/helpers.js:107:18) Sep 06 22:18:15 volumio volumio[1381]: at Object. (/data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/fs/index.js:3:11) Sep 06 22:18:15 volumio volumio[1381]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Sep 06 22:18:15 volumio volumio[1381]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Sep 06 22:18:15 volumio volumio[1381]: at Module.load (internal/modules/cjs/loader.js:979:32) Sep 06 22:18:15 volumio volumio[1381]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Sep 06 22:18:15 volumio volumio[1381]: at Module.require (internal/modules/cjs/loader.js:1003:19) Sep 06 22:18:15 volumio volumio[1381]: at require (internal/modules/cjs/helpers.js:107:18) Sep 06 22:18:15 volumio volumio[1381]: at Object. (/data/plugins/user_interface/peppyspectrum/node_modules/fs-extra/lib/index.js:8:12) Sep 06 22:18:15 volumio volumio[1381]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Sep 06 22:18:15 volumio volumio[1381]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Sep 06 22:18:15 volumio volumio[1381]: at Module.load (internal/modules/cjs/loader.js:979:32) Sep 06 22:18:15 volumio volumio[1381]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Sep 06 22:18:15 volumio volumio[1381]: error: !!!! WARNING !!!! Sep 06 22:18:15 volumio volumio[1381]: info: Preparing to generate the ALSA configuration file Sep 06 22:18:15 volumio volumio[1381]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 22:18:15 volumio volumio[1381]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 06 22:18:15 volumio volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 06 22:18:15 volumio volumio[1381]: info: Reading ALSA contributions from plugins. Sep 06 22:18:15 volumio volumio[1381]: info: Asound.conf file written Sep 06 22:18:15 volumio sudo[15148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 06 22:18:15 volumio sudo[15148]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:15 volumio sudo[15148]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:15 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:18:15 volumio volumio[1381]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Sep 06 22:18:15 volumio volumio[1381]: Hardware is initialized using a generic method Sep 06 22:18:15 volumio volumio[1381]: No state is present for card AUDIO Sep 06 22:18:15 volumio volumio[1381]: info: Output device has changed, restarting MPD Sep 06 22:18:15 volumio sudo[15153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 22:18:15 volumio sudo[15153]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:15 volumio sudo[15153]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:15 volumio volumio[1381]: info: Output device has changed, restarting Shairport Sync Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:15 volumio sudo[15156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 22:18:15 volumio sudo[15156]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:15 volumio systemd[1]: Stopping Music Player Daemon... Sep 06 22:18:15 volumio volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 22:18:15 volumio volumio[1381]: info: Done. Sep 06 22:18:15 volumio systemd[1]: mpd.service: Succeeded. Sep 06 22:18:15 volumio systemd[1]: Stopped Music Player Daemon. Sep 06 22:18:15 volumio volumio[1381]: info: MPD Permissions set Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::volumioGetState Sep 06 22:18:15 volumio volumio[1381]: info: CorePlayQueue::getTrack 0 Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 06 22:18:15 volumio systemd[1]: Starting Music Player Daemon... Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 22:18:15 volumio volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 22:18:15 volumio volumio[1381]: info: Starting Shairport Sync Sep 06 22:18:15 volumio sudo[15169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 22:18:15 volumio sudo[15169]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 22:18:15 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 06 22:18:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 22:18:15 volumio sudo[15161]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 22:18:15 volumio sudo[15161]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 22:18:15 volumio sudo[15169]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:15 volumio sudo[15161]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:15 volumio volumio[1381]: info: Shairport-Sync Started Sep 06 22:18:16 volumio mpd[15173]: Sep 06 22:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 22:18:16 volumio systemd[1]: Started Music Player Daemon. Sep 06 22:18:16 volumio sudo[15156]: pam_unix(sudo:session): session closed for user root Sep 06 22:18:16 volumio volumio[1381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 22:18:16 volumio volumio[1381]: TypeError: Cannot read property 'printToastMessage' of null Sep 06 22:18:16 volumio volumio[1381]: at /volumio/app/index.js:1201:32 Sep 06 22:18:16 volumio volumio[1381]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 06 22:18:16 volumio volumio[1381]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 06 22:18:16 volumio volumio[1381]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1199:13) Sep 06 22:18:16 volumio volumio[1381]: at /volumio/app/plugins/music_service/mpd/index.js:797:30 Sep 06 22:18:16 volumio volumio[1381]: at /volumio/app/plugins/music_service/mpd/index.js:941:9 Sep 06 22:18:16 volumio volumio[1381]: at ChildProcess.exithandler (child_process.js:374:7) Sep 06 22:18:16 volumio volumio[1381]: at ChildProcess.emit (events.js:400:28) Sep 06 22:18:16 volumio volumio[1381]: at maybeClose (internal/child_process.js:1088:16) Sep 06 22:18:16 volumio volumio[1381]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Sep 06 22:18:16 volumio volumio[1381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 22:18:16 volumio sudo[15188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 22:17 Sep 06 22:18:16 volumio sudo[15188]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"