-- 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"