-- Logs begin at Fri 2024-10-04 03:41:22 EEST, end at Fri 2024-10-04 20:12:17 EEST. -- Oct 04 20:11:00 volumio2 volumio[1146]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline (system info unavailable) Oct 04 20:11:02 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:03 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:04 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:05 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:06 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:06 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 288 Oct 04 20:11:06 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:06 volumio2 volumio[1146]: info: Prefetching next song Oct 04 20:11:06 volumio2 volumio[1146]: info: DOING PREFETCH IN MPD Oct 04 20:11:06 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Музыка/System of a down/System Of A Down - System Of A Down/(11) Cubert.mp3" Oct 04 20:11:06 volumio2 volumio[1146]: info: Oct 04 20:11:06 volumio2 volumio[1146]: ---------------------------- MPD announces system playlist update Oct 04 20:11:06 volumio2 volumio[1146]: info: Ignoring MPD Status Update Oct 04 20:11:06 volumio2 volumio[1146]: info: sendMpdCommand add "INTERNAL/Музыка/System of a down/System Of A Down - System Of A Down/(11) Cubert.mp3" took 2 milliseconds Oct 04 20:11:06 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 04 20:11:06 volumio2 volumio[1146]: info: Oct 04 20:11:06 volumio2 volumio[1146]: ---------------------------- MPD announces system playlist update Oct 04 20:11:06 volumio2 volumio[1146]: info: Ignoring MPD Status Update Oct 04 20:11:06 volumio2 volumio[1146]: info: ------------------------------ 2ms Oct 04 20:11:06 volumio2 volumio[1146]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 04 20:11:06 volumio2 volumio[1146]: info: ------------------------------ 2ms Oct 04 20:11:07 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:08 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:09 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:10 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:11 volumio2 volumio[1146]: info: CoreStateMachine::startPlaybackTimer Oct 04 20:11:11 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:11 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppySpectrum ---peppyspectrum status play Oct 04 20:11:12 volumio2 sudo[15950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 04 20:11:12 volumio2 sudo[15950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15950]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 sudo[15953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 04 20:11:12 volumio2 sudo[15953]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 volumio[1146]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 04 20:11:12 volumio2 volumio[1146]: info: peppymeterbasic Daemon Started Oct 04 20:11:12 volumio2 sudo[15953]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: peppyspectrum Daemon Started Oct 04 20:11:12 volumio2 volumio[1146]: info: Oct 04 20:11:12 volumio2 volumio[1146]: ---------------------------- MPD announces system playlist update Oct 04 20:11:12 volumio2 volumio[1146]: info: Ignoring MPD Status Update Oct 04 20:11:12 volumio2 volumio[1146]: info: Oct 04 20:11:12 volumio2 volumio[1146]: ---------------------------- MPD announces state update: player Oct 04 20:11:12 volumio2 volumio[1146]: info: ControllerMpd::getState Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Oct 04 20:11:12 volumio2 volumio[1146]: info: Oct 04 20:11:12 volumio2 volumio[1146]: ---------------------------- MPD announces system playlist update Oct 04 20:11:12 volumio2 volumio[1146]: info: Ignoring MPD Status Update Oct 04 20:11:12 volumio2 volumio[1146]: info: Oct 04 20:11:12 volumio2 volumio[1146]: ---------------------------- MPD announces state update: player Oct 04 20:11:12 volumio2 volumio[1146]: info: ControllerMpd::getState Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Oct 04 20:11:12 volumio2 volumio[1146]: info: ------------------------------ 2ms Oct 04 20:11:12 volumio2 volumio[1146]: info: sendMpdCommand status took 7 milliseconds Oct 04 20:11:12 volumio2 volumio[1146]: info: ------------------------------ 8ms Oct 04 20:11:12 volumio2 volumio[1146]: info: sendMpdCommand status took 8 milliseconds Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::parseState Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::parseState Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 20:11:12 volumio2 volumio[1146]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 04 20:11:12 volumio2 volumio[1146]: verbose: ControllerMpd::parseTrackInfo Oct 04 20:11:12 volumio2 volumio[1146]: info: ControllerMpd::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::servicePushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":109,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cubert","artist":"System Of A Down","album":"System Of A Down","uri":"INTERNAL/Музыка/System of a down/System Of A Down - System Of A Down/(11) Cubert.mp3","trackType":"mp3"} Oct 04 20:11:12 volumio2 volumio[1146]: verbose: CURRENT POSITION 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::syncState stateService play Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::syncState currentStatus play Oct 04 20:11:12 volumio2 volumio[1146]: info: Received an update from plugin. extracting info from payload Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 04 20:11:12 volumio2 volumio[1146]: info: ControllerMpd::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::servicePushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":109,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Cubert","artist":"System Of A Down","album":"System Of A Down","uri":"INTERNAL/Музыка/System of a down/System Of A Down - System Of A Down/(11) Cubert.mp3","trackType":"mp3"} Oct 04 20:11:12 volumio2 volumio[1146]: verbose: CURRENT POSITION 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::syncState stateService play Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::syncState currentStatus play Oct 04 20:11:12 volumio2 volumio[1146]: info: Received an update from plugin. extracting info from payload Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreStateMachine::pushState Oct 04 20:11:12 volumio2 volumio[1146]: info: CorePlayQueue::getTrack 289 Oct 04 20:11:12 volumio2 volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 04 20:11:12 volumio2 volumio[1146]: info: ------------------------------ 27ms Oct 04 20:11:12 volumio2 volumio[1146]: info: ------------------------------ 26ms Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 04 20:11:12 volumio2 sudo[15956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 04 20:11:12 volumio2 sudo[15956]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15956]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 04 20:11:12 volumio2 sudo[15959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 04 20:11:12 volumio2 sudo[15959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppyMeterBasic ---peppymeterbasic status play Oct 04 20:11:12 volumio2 sudo[15959]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 sudo[15962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 04 20:11:12 volumio2 sudo[15962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15962]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppySpectrum ---peppyspectrum status play Oct 04 20:11:12 volumio2 sudo[15965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Oct 04 20:11:12 volumio2 sudo[15965]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15965]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppySpectrum ---peppyspectrum status play Oct 04 20:11:12 volumio2 sudo[15968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 04 20:11:12 volumio2 sudo[15968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15968]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppySpectrum ---peppyspectrum status play Oct 04 20:11:12 volumio2 sudo[15971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 04 20:11:12 volumio2 sudo[15971]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15971]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:12 volumio2 volumio[1146]: info: PeppySpectrum ---peppyspectrum status play Oct 04 20:11:12 volumio2 sudo[15974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 04 20:11:12 volumio2 sudo[15974]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15974]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 04 20:11:12 volumio2 volumio[1146]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 04 20:11:12 volumio2 volumio[1146]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 04 20:11:12 volumio2 volumio[1146]: info: peppyspectrum Daemon Started Oct 04 20:11:12 volumio2 volumio[1146]: info: peppyspectrum Daemon Started Oct 04 20:11:12 volumio2 volumio[1146]: info: peppymeterbasic Daemon Started Oct 04 20:11:12 volumio2 sudo[15977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 04 20:11:12 volumio2 sudo[15977]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:12 volumio2 sudo[15977]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:12 volumio2 volumio[1146]: info: peppyspectrum Daemon Started Oct 04 20:11:13 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 04 20:11:13 volumio2 dhcpcd[834]: wlan0: carrier lost Oct 04 20:11:13 volumio2 avahi-daemon[749]: Withdrawing address record for 192.168.1.51 on wlan0. Oct 04 20:11:13 volumio2 avahi-daemon[749]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.51. Oct 04 20:11:13 volumio2 avahi-daemon[749]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 04 20:11:13 volumio2 volumio[1146]: info: Discovery: A device disappeared from network Oct 04 20:11:13 volumio2 volumio[1146]: info: Discovery: Device volumio2 disappeared from network Oct 04 20:11:13 volumio2 dhcpcd[834]: wlan0: deleting route to 192.168.1.0/24 Oct 04 20:11:13 volumio2 dhcpcd[834]: wlan0: deleting default route via 192.168.1.1 Oct 04 20:11:13 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d0:ee:07:61:ac:86 reason=4 locally_generated=1 Oct 04 20:11:15 volumio2 ntpd[9242]: Deleting interface #3 wlan0, 192.168.1.51#123, interface stats: received=363, sent=374, dropped=0, active_time=5481 secs Oct 04 20:11:15 volumio2 ntpd[9242]: 194.54.80.29 local addr 192.168.1.51 -> Oct 04 20:11:15 volumio2 ntpd[9242]: 91.236.251.14 local addr 192.168.1.51 -> Oct 04 20:11:15 volumio2 ntpd[9242]: 212.1.104.9 local addr 192.168.1.51 -> Oct 04 20:11:15 volumio2 ntpd[9242]: 193.106.144.6 local addr 192.168.1.51 -> Oct 04 20:11:15 volumio2 ntpd[9242]: 193.106.144.7 local addr 192.168.1.51 -> Oct 04 20:11:15 volumio2 ntpd[9242]: 162.159.200.1 local addr 192.168.1.51 -> Oct 04 20:11:17 volumio2 wpa_supplicant[1051]: wlan0: SME: Trying to authenticate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:11:17 volumio2 kernel: wlan0: authenticate with d0:ee:07:61:ac:86 Oct 04 20:11:17 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:11:17 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 2/3) Oct 04 20:11:17 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 3/3) Oct 04 20:11:17 volumio2 kernel: wlan0: authentication with d0:ee:07:61:ac:86 timed out Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:18 volumio2 go-librespot[19308]: time="2024-10-04T20:11:18+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:19 volumio2 go-librespot[19308]: time="2024-10-04T20:11:19+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:21 volumio2 go-librespot[19308]: time="2024-10-04T20:11:21+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:22 volumio2 go-librespot[19308]: time="2024-10-04T20:11:22+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:25 volumio2 go-librespot[19308]: time="2024-10-04T20:11:25+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:30 volumio2 go-librespot[19308]: time="2024-10-04T20:11:30+03:00" level=error msg="did not receive last pong from dealer, 35s passed" Oct 04 20:11:30 volumio2 volumio[1146]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline Oct 04 20:11:35 volumio2 go-librespot[19308]: time="2024-10-04T20:11:35+03:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Oct 04 20:11:35 volumio2 go-librespot[19308]: time="2024-10-04T20:11:35+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:36 volumio2 go-librespot[19308]: time="2024-10-04T20:11:36+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:37 volumio2 go-librespot[19308]: time="2024-10-04T20:11:37+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:39 volumio2 go-librespot[19308]: time="2024-10-04T20:11:39+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:40 volumio2 go-librespot[19308]: time="2024-10-04T20:11:40+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:42 volumio2 nmbd[1023]: [2024/10/04 20:11:42.511605, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Oct 04 20:11:42 volumio2 nmbd[1023]: reload_interfaces: No subnets to listen to. Waiting.. Oct 04 20:11:43 volumio2 go-librespot[19308]: time="2024-10-04T20:11:43+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:43 volumio2 volumio[1146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:11:43 volumio2 volumio[1146]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 04 20:11:43 volumio2 volumio[1146]: at doSend (dgram.js:714:16) Oct 04 20:11:43 volumio2 volumio[1146]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Oct 04 20:11:43 volumio2 volumio[1146]: at afterDns (dgram.js:660:5) Oct 04 20:11:43 volumio2 volumio[1146]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Oct 04 20:11:43 volumio2 volumio[1146]: errno: -101, Oct 04 20:11:43 volumio2 volumio[1146]: code: 'ENETUNREACH', Oct 04 20:11:43 volumio2 volumio[1146]: syscall: 'send', Oct 04 20:11:43 volumio2 volumio[1146]: address: '255.255.255.255', Oct 04 20:11:43 volumio2 volumio[1146]: port: 3483 Oct 04 20:11:43 volumio2 volumio[1146]: } Oct 04 20:11:43 volumio2 volumio[1146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:11:44 volumio2 sudo[16085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 20:10 Oct 04 20:11:44 volumio2 sudo[16085]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:44 volumio2 sudo[16085]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:44 volumio2 volumio-remote-updater[748]: [2024-10-04 20:11:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 04 20:11:44 volumio2 go-librespot[19308]: time="2024-10-04T20:11:44+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 04 20:11:44 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 04 20:11:44 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 04 20:11:44 volumio2 volumio-remote-updater[748]: [2024-10-04 20:11:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 04 20:11:44 volumio2 systemd[1]: Started dynamicswap service. Oct 04 20:11:44 volumio2 systemd[1]: dynamicswap.service: Succeeded. Oct 04 20:11:44 volumio2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:11:44 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 04 20:11:44 volumio2 systemd[1]: Stopped Volumio Backend Module. Oct 04 20:11:44 volumio2 systemd[1]: Started Volumio Backend Module. Oct 04 20:11:44 volumio2 systemd[1]: Started dynamicswap service. Oct 04 20:11:44 volumio2 systemd[1]: dynamicswap.service: Succeeded. Oct 04 20:11:46 volumio2 volumio[16099]: info: ------------------------------------------- Oct 04 20:11:46 volumio2 volumio[16099]: info: ----- Volumio3 ---- Oct 04 20:11:46 volumio2 volumio[16099]: info: ------------------------------------------- Oct 04 20:11:46 volumio2 volumio[16099]: info: ----- System startup ---- Oct 04 20:11:46 volumio2 volumio[16099]: info: ------------------------------------------- Oct 04 20:11:46 volumio2 kernel: wlan0: authenticate with d0:ee:07:61:ac:86 Oct 04 20:11:46 volumio2 wpa_supplicant[1051]: wlan0: SME: Trying to authenticate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:11:46 volumio2 volumio[16099]: info: MYVOLUMIO Environment detected Oct 04 20:11:46 volumio2 volumio[16099]: info: Plugin folders cleanup Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning into folder /volumio/app/plugins/ Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category audio_interface Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category miscellanea Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category music_service Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category plugins.json Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category system_controller Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category user_interface Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning into folder /data/plugins/ Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category audio_interface Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category music_service Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category system_controller Oct 04 20:11:46 volumio2 volumio[16099]: info: Scanning category user_interface Oct 04 20:11:46 volumio2 volumio[16099]: info: Plugin folders cleanup completed Oct 04 20:11:46 volumio2 volumio[16099]: info: ------------------------------------------- Oct 04 20:11:46 volumio2 volumio[16099]: info: ----- Core plugins startup ---- Oct 04 20:11:46 volumio2 volumio[16099]: info: ------------------------------------------- Oct 04 20:11:46 volumio2 volumio[16099]: info: Loading plugins from folder /volumio/app/plugins/ Oct 04 20:11:46 volumio2 volumio[16099]: info: Adding plugin upnp to MyMusic Plugins Oct 04 20:11:46 volumio2 volumio[16099]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 04 20:11:46 volumio2 volumio[16099]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 04 20:11:46 volumio2 volumio[16099]: info: Loading plugins from folder /data/plugins/ Oct 04 20:11:46 volumio2 volumio[16099]: info: Loading plugin "system"... Oct 04 20:11:46 volumio2 volumio[16099]: info: Loading plugin "appearance"... Oct 04 20:11:47 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:11:47 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 2/3) Oct 04 20:11:47 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 3/3) Oct 04 20:11:47 volumio2 kernel: wlan0: authentication with d0:ee:07:61:ac:86 timed out Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "network"... Oct 04 20:11:47 volumio2 volumio[16099]: info: Refreshing Cached IP Addresses Oct 04 20:11:47 volumio2 sudo[16126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 20:11:47 volumio2 sudo[16126]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:47 volumio2 sudo[16128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "services"... Oct 04 20:11:47 volumio2 sudo[16126]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:47 volumio2 sudo[16128]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "alsa_controller"... Oct 04 20:11:47 volumio2 sudo[16128]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:47 volumio2 sudo[16134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 04 20:11:47 volumio2 sudo[16134]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:11:47 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "wizard"... Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "networkfs"... Oct 04 20:11:47 volumio2 volumio[16099]: info: Starting Udev Watcher for removable devices Oct 04 20:11:47 volumio2 volumio[16099]: info: Ignoring mount for partition: boot Oct 04 20:11:47 volumio2 volumio[16099]: info: Ignoring mount for partition: volumio Oct 04 20:11:47 volumio2 volumio[16099]: info: Ignoring mount for partition: volumio_data Oct 04 20:11:47 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "volumio_command_line_client"... Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "upnp"... Oct 04 20:11:47 volumio2 volumio[16099]: info: [1728061907872] Starting Upmpd Daemon Oct 04 20:11:47 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "my_music"... Oct 04 20:11:47 volumio2 volumio[16099]: info: Loading plugin "mpd"... Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "upnp_browser"... Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "alarm-clock"... Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "airplay_emulation"... Oct 04 20:11:48 volumio2 volumio[16099]: info: Starting Shairport Sync Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "last_100"... Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "webradio"... Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:11:48 volumio2 go-librespot[19308]: time="2024-10-04T20:11:48+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "i2s_dacs"... Oct 04 20:11:48 volumio2 volumio[16099]: info: I2S DAC not set, start Auto-detection Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "volumiodiscovery"... Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** For more information see Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:11:48 volumio2 volumio[16099]: *** WARNING *** For more information see Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** For more information see Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:11:48 volumio2 node[16099]: *** WARNING *** For more information see Oct 04 20:11:48 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 04 20:11:48 volumio2 volumio[16099]: info: Discovery: Started advertising with name: Volumio2 Oct 04 20:11:48 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:11:48 volumio2 volumio[16099]: info: Loading plugin "jellyfin"... Oct 04 20:11:49 volumio2 go-librespot[19308]: time="2024-10-04T20:11:49+03:00" level=debug msg="renewing login5 access token" Oct 04 20:11:49 volumio2 volumio[16099]: info: Loading plugin "jellyfin_server"... Oct 04 20:11:49 volumio2 volumio[16099]: info: Loading plugin "spop"... Oct 04 20:11:49 volumio2 volumio-remote-updater[748]: [2024-10-04 20:11:49] [connect] Successful connection Oct 04 20:11:50 volumio2 volumio[16099]: info: Loading plugin "squeezelite_mc"... Oct 04 20:11:51 volumio2 volumio[16099]: info: Loading plugin "youtube2"... Oct 04 20:11:51 volumio2 volumio[16099]: info: Loading plugin "ytcr"... Oct 04 20:11:53 volumio2 volumio[16099]: info: Loading plugin "ytmusic"... Oct 04 20:11:54 volumio2 wpa_supplicant[1051]: wlan0: SME: Trying to authenticate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:11:54 volumio2 kernel: wlan0: authenticate with d0:ee:07:61:ac:86 Oct 04 20:11:54 volumio2 volumio[16099]: info: Loading plugin "now_playing"... Oct 04 20:11:54 volumio2 sudo[16134]: pam_unix(sudo:session): session closed for user root Oct 04 20:11:54 volumio2 wpa_supplicant[1051]: wlan0: Trying to associate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:11:54 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:11:54 volumio2 kernel: wlan0: authenticated Oct 04 20:11:54 volumio2 kernel: wlan0: associate with d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:11:55 volumio2 wpa_supplicant[1051]: wlan0: Associated with d0:ee:07:61:ac:86 Oct 04 20:11:55 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 04 20:11:55 volumio2 kernel: wlan0: RX AssocResp from d0:ee:07:61:ac:86 (capab=0x831 status=0 aid=1) Oct 04 20:11:55 volumio2 kernel: wlan0: associated Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "outputs"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "albumart"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Plugin example_plugin is not enabled Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "inputs"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "updater_comm"... Oct 04 20:11:55 volumio2 volumio[16099]: Forking 1 albumart workers Oct 04 20:11:55 volumio2 volumio[16099]: info: Plugin mpdemulation is not enabled Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "rest_api"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "websocket"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Starting Socket.io Server version 2.3.0 Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "fusiondsp"... Oct 04 20:11:55 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin fusiondsp Oct 04 20:11:55 volumio2 volumio[16099]: Starting albumart workers Oct 04 20:11:55 volumio2 volumio[16099]: info: Loading plugin "RoonBridge"... Oct 04 20:11:56 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin RoonBridge Oct 04 20:11:56 volumio2 volumio[16099]: info: Loading plugin "minidlna"... Oct 04 20:11:56 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin minidlna Oct 04 20:11:56 volumio2 volumio[16099]: info: Loading plugin "plexamp"... Oct 04 20:11:57 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin plexamp Oct 04 20:11:57 volumio2 volumio[16099]: info: Loading plugin "squeezelite"... Oct 04 20:11:57 volumio2 volumio[16099]: info: [Squeezelite] Initiated plugin Oct 04 20:11:57 volumio2 volumio[16099]: info: Loading plugin "volroon"... Oct 04 20:11:58 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin volroon Oct 04 20:11:58 volumio2 volumio[16099]: info: Loading plugin "volusonic"... Oct 04 20:11:58 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin volusonic Oct 04 20:11:58 volumio2 volumio[16099]: info: Loading plugin "backup_restore"... Oct 04 20:11:58 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin backup_restore Oct 04 20:11:58 volumio2 volumio[16099]: info: Loading plugin "Systeminfo"... Oct 04 20:11:59 volumio2 volumio[16099]: info: Loading plugin "peppymeterbasic"... Oct 04 20:11:59 volumio2 volumio[16099]: info: Loading plugin "peppyspectrum"... Oct 04 20:11:59 volumio2 volumio[16099]: info: Loading plugin "randomizer"... Oct 04 20:11:59 volumio2 volumio[16099]: info: Applying required configuration parameters for plugin randomizer Oct 04 20:12:00 volumio2 volumio[16099]: info: Loading i18n strings for locale ua Oct 04 20:12:00 volumio2 volumio[16099]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:00 volumio2 volumio[16099]: Updating browse sources language Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::initPlayerControls Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: Express server listening on port 3000 Oct 04 20:12:00 volumio2 volumio[16099]: [Metrics] WebUI: 14s 548.01ms Oct 04 20:12:00 volumio2 volumio[16099]: info: Setting Device type: x86 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::resetVolumioState Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::getcurrentVolume Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:00 volumio2 go-librespot[19308]: time="2024-10-04T20:12:00+03:00" level=debug msg="renewing login5 access token" Oct 04 20:12:00 volumio2 go-librespot[19308]: time="2024-10-04T20:12:00+03:00" level=error msg="did not receive last pong from dealer, 65s passed" Oct 04 20:12:00 volumio2 sudo[16171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 20:12:00 volumio2 sudo[16171]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 sudo[16171]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:00 volumio2 volumio[16099]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 04 20:12:00 volumio2 volumio[16099]: info: Completed loading Core Plugins Oct 04 20:12:00 volumio2 volumio[16099]: info: Preparing to generate the ALSA configuration file Oct 04 20:12:00 volumio2 sudo[16176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 20:12:00 volumio2 sudo[16176]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 sudo[16176]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:00 volumio2 volumio[16099]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 04 20:12:00 volumio2 volumio[16099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 04 20:12:00 volumio2 volumio[16099]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:00 volumio2 volumio[16099]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:00 volumio2 volumio[16099]: info: Reading ALSA contributions from plugins. Oct 04 20:12:00 volumio2 volumio[16099]: info: VolumeController:: Volume=100 Mute =false Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::pushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::updateTrackBlock Oct 04 20:12:00 volumio2 volumio[16099]: info: CorePlayQueue::getTrackBlock Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:00 volumio2 volumio[16099]: info: Reloading queue from file Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::setRepeat null single undefined Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::pushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::setRandom null Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::pushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:00 volumio2 volumio[16099]: info: VolumeController:: Volume=100 Mute =false Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreStateMachine::pushState Oct 04 20:12:00 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:00 volumio2 volumio[16099]: info: Asound.conf file unchanged, so no further update is needed Oct 04 20:12:00 volumio2 volumio[16099]: info: Output device has changed, restarting MPD Oct 04 20:12:00 volumio2 sudo[16186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 04 20:12:00 volumio2 sudo[16186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 sudo[16186]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:00 volumio2 sudo[16189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 04 20:12:00 volumio2 sudo[16189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 sudo[16189]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:00 volumio2 volumio[16099]: info: Output device has changed, restarting Shairport Sync Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:00 volumio2 sudo[16193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 04 20:12:00 volumio2 sudo[16193]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 systemd[1]: Stopping Music Player Daemon... Oct 04 20:12:00 volumio2 volumio[16099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: volroon::Output device has changed Oct 04 20:12:00 volumio2 volumio[16099]: info: ___________ START PLUGINS ___________ Oct 04 20:12:00 volumio2 volumio[16099]: info: ControllerMpd::onStart: Initializing MPD Oct 04 20:12:00 volumio2 volumio[16099]: info: Creating MPD Configuration file Oct 04 20:12:00 volumio2 sudo[16201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 04 20:12:00 volumio2 sudo[16201]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 sudo[16201]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:00 volumio2 sudo[16204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 04 20:12:00 volumio2 sudo[16204]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:00 volumio2 volumio[16099]: info: [1728061920963] CoreMusicLibrary::Adding element Media Servers Oct 04 20:12:00 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:01 volumio2 volumio[16099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:01 volumio2 systemd[1]: mpd.service: Succeeded. Oct 04 20:12:01 volumio2 systemd[1]: Stopped Music Player Daemon. Oct 04 20:12:01 volumio2 systemd[1]: Starting Music Player Daemon... Oct 04 20:12:01 volumio2 volumio[16099]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:01 volumio2 volumio[16099]: info: [1728061921129] CoreMusicLibrary::Adding element Last_100 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:01 volumio2 volumio[16099]: info: [1728061921132] CoreMusicLibrary::Adding element Webradio Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 sudo[16209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 04 20:12:01 volumio2 sudo[16209]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:12:01 volumio2 volumio[16099]: info: Initializing BBC Radios Oct 04 20:12:01 volumio2 sudo[16209]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:01 volumio2 volumio[16099]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:01 volumio2 kernel: wlan0: deauthenticated from d0:ee:07:61:ac:86 (Reason: 15=4WAY_HANDSHAKE_TIMEOUT) Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 20:12:01 volumio2 volumio[16099]: info: Discovery: Getting this device information Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioGetState Oct 04 20:12:01 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 20:12:01 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d0:ee:07:61:ac:86 reason=15 Oct 04 20:12:01 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="WIFI5" auth_failures=1 duration=10 reason=CONN_FAILED Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:01 volumio2 volumio[16099]: info: [1728061921273] CoreMusicLibrary::Adding element Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: info: [jellyfin] Initialized plugin with device info: {"id":"01a5e55c-dda8-49b3-ab3b-5c5bd763a7d0","host":"http://127.0.0.1","name":"Volumio2","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"ATB","track":"My Saving Grace","albumart":"http://127.0.0.1/albumart?cacheid=153&web=ATB/Future%20Memories/extralarge&path=%2FINTERNAL%2F%D0%9C%D1%83%D0%B7%D1%8B%D0%BA%D0%B0%2FAtb%2FATB%20-%20Future%20Memories&metadata=false"}} Oct 04 20:12:01 volumio2 volumio[16099]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Oct 04 20:12:01 volumio2 volumio[16099]: info: Creating Spotify config file Oct 04 20:12:01 volumio2 sudo[16227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Oct 04 20:12:01 volumio2 sudo[16227]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:01 volumio2 sudo[16227]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:01 volumio2 volumio[16099]: info: [squeezelite_mc] Starting proxy server... Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:01 volumio2 volumio[16099]: info: [1728061921432] CoreMusicLibrary::Adding element YouTube2 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:01 volumio2 volumio[16099]: info: [1728061921461] CoreMusicLibrary::Adding element YouTube Music Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:01 volumio2 volumio[16099]: info: [now-playing] ConfigUpdater: config is up to date. Oct 04 20:12:01 volumio2 volumio[16099]: info: Loading i18n strings for locale ua Oct 04 20:12:01 volumio2 volumio[16099]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:01 volumio2 volumio[16099]: info: FusionDsp - mixtype--------------------- Hardware Oct 04 20:12:01 volumio2 volumio[16099]: info: Loading i18n strings for locale ua Oct 04 20:12:01 volumio2 volumio[16099]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:01 volumio2 sudo[16230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 04 20:12:01 volumio2 sudo[16230]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:01 volumio2 sudo[16230]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:01 volumio2 volumio[16099]: info: Loading i18n strings for locale ua Oct 04 20:12:01 volumio2 volumio[16099]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:01 volumio2 volumio[16099]: Updating browse sources language Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:01 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:01 volumio2 volumio[16099]: info: No Token - please link Plex Account Oct 04 20:12:01 volumio2 sudo[16235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 04 20:12:01 volumio2 sudo[16235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 04 20:12:01 volumio2 systemd[1]: Reloading. Oct 04 20:12:01 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:02 volumio2 sudo[16254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 04 20:12:02 volumio2 sudo[16254]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:02 volumio2 volumio[16099]: info: volroon::Roon API Services Started Oct 04 20:12:02 volumio2 volumio[16099]: info: Loading i18n strings for locale ua Oct 04 20:12:02 volumio2 volumio[16099]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:02 volumio2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:02 volumio2 volumio[16099]: Updating browse sources language Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:02 volumio2 volumio[16099]: info: [1728061922157] CoreMusicLibrary::Adding element Volusonic Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Volusonic Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:02 volumio2 volumio-remote-updater[748]: [2024-10-04 20:12:02] [connect] Successful connection Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:02 volumio2 systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:02 volumio2 volumio[16099]: info: [1728061922260] CoreMusicLibrary::Adding element Randomizer Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Jellyfin Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube2 Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source YouTube Music Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Volusonic Oct 04 20:12:02 volumio2 volumio[16099]: Cannot find translation for source Randomizer Oct 04 20:12:02 volumio2 volumio[16099]: info: Volumio Calling Home Oct 04 20:12:02 volumio2 sudo[16235]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 sudo[16254]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 volumio[16099]: info: [squeezelite_mc] Proxy server started on port 34795 Oct 04 20:12:02 volumio2 volumio[16099]: info: Preparing to generate the ALSA configuration file Oct 04 20:12:02 volumio2 volumio[16099]: info: PlexAmp::Plex failed to connect Oct 04 20:12:02 volumio2 mpd[16214]: Oct 04 20:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:02 volumio2 volumio[16099]: info: [now-playing] App is listening on port 4004. Oct 04 20:12:02 volumio2 volumio[16099]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 04 20:12:02 volumio2 volumio[16099]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline Oct 04 20:12:02 volumio2 volumio[16099]: Unhandled rejection Error: No sockets available, cannot start. Oct 04 20:12:02 volumio2 volumio[16099]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 04 20:12:02 volumio2 volumio[16099]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 04 20:12:02 volumio2 volumio[16099]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 04 20:12:02 volumio2 volumio[16099]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 04 20:12:02 volumio2 volumio[16099]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 04 20:12:02 volumio2 volumio[16099]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 04 20:12:02 volumio2 volumio[16099]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 04 20:12:02 volumio2 volumio[16099]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 04 20:12:02 volumio2 volumio[16099]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 04 20:12:02 volumio2 volumio[16099]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 04 20:12:02 volumio2 volumio[16099]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 04 20:12:02 volumio2 volumio[16099]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 04 20:12:02 volumio2 volumio[16099]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 04 20:12:02 volumio2 volumio[16099]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 04 20:12:02 volumio2 volumio[16099]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 04 20:12:02 volumio2 volumio[16099]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 04 20:12:02 volumio2 volumio[16099]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Oct 04 20:12:02 volumio2 volumio[16099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 04 20:12:02 volumio2 volumio[16099]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:02 volumio2 volumio[16099]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:02 volumio2 volumio[16099]: info: Reading ALSA contributions from plugins. Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (mlb773qfhglf851qrl5k3fqpkq): Oct 04 20:12:02 volumio2 volumio[16099]: (ConnectionError) (YouTube) Connection error in getting lounge token Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { Oct 04 20:12:02 volumio2 volumio[16099]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: ConnectionError: (YouTube) Connection error in getting lounge token Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 04 20:12:02 volumio2 volumio[16099]: at async Promise.all (index 0) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 volumio[16099]: Going to generate fresh screen Id and try again... Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (je64tvadijgk6g3lkr0fft4m6a): Oct 04 20:12:02 volumio2 volumio[16099]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { Oct 04 20:12:02 volumio2 volumio[16099]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: ConnectionError: (YouTube Music) Connection error in getting lounge token Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 04 20:12:02 volumio2 volumio[16099]: at async Promise.all (index 1) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 volumio[16099]: Going to generate fresh screen Id and try again... Oct 04 20:12:02 volumio2 volumio[16099]: info: ::Roon Bridge has successfully started Oct 04 20:12:02 volumio2 volumio[16099]: info: squeezelite reloaded Oct 04 20:12:02 volumio2 systemd[1]: Started Music Player Daemon. Oct 04 20:12:02 volumio2 sudo[16204]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 sudo[16193]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 volumio[16099]: info: minidlna: Found miniDLNA version 1.2.1 Oct 04 20:12:02 volumio2 volumio[16099]: info: minidlna: Starting minidlna.service Oct 04 20:12:02 volumio2 sudo[16264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Oct 04 20:12:02 volumio2 sudo[16264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:02 volumio2 volumio[16099]: info: MPD Permissions set Oct 04 20:12:02 volumio2 volumio[16099]: info: Upmpdcli Daemon Started Oct 04 20:12:02 volumio2 systemd[16267]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:02 volumio2 systemd[16267]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:02 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:02 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:02 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:02 volumio2 sudo[16268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 04 20:12:02 volumio2 sudo[16268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 04 20:12:02 volumio2 volumio[16099]: LoungeScreenDisconnected { Oct 04 20:12:02 volumio2 volumio[16099]: AID: null, Oct 04 20:12:02 volumio2 volumio[16099]: name: 'loungeScreenDisconnected', Oct 04 20:12:02 volumio2 volumio[16099]: payload: {} Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:02 volumio2 volumio[16099]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 04 20:12:02 volumio2 volumio[16099]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 04 20:12:02 volumio2 volumio[16099]: at new Promise () Oct 04 20:12:02 volumio2 volumio[16099]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 04 20:12:02 volumio2 volumio[16099]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 04 20:12:02 volumio2 volumio[16099]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async Promise.all (index 0) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] Failed to start receiver: Oct 04 20:12:02 volumio2 volumio[16099]: (AppError) Failed to start YouTubeApp Oct 04 20:12:02 volumio2 volumio[16099]: --->(SessionError) (YouTube) Failed to establish session Oct 04 20:12:02 volumio2 volumio[16099]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { Oct 04 20:12:02 volumio2 volumio[16099]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: AppError: Failed to start YouTubeApp Oct 04 20:12:02 volumio2 volumio[16099]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 sudo[16264]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 04 20:12:02 volumio2 volumio[16099]: LoungeScreenDisconnected { Oct 04 20:12:02 volumio2 volumio[16099]: AID: null, Oct 04 20:12:02 volumio2 volumio[16099]: name: 'loungeScreenDisconnected', Oct 04 20:12:02 volumio2 volumio[16099]: payload: {} Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:02 volumio2 volumio[16099]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 04 20:12:02 volumio2 volumio[16099]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 04 20:12:02 volumio2 volumio[16099]: at new Promise () Oct 04 20:12:02 volumio2 volumio[16099]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 04 20:12:02 volumio2 volumio[16099]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 04 20:12:02 volumio2 volumio[16099]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Oct 04 20:12:02 volumio2 volumio[16099]: at Array.forEach () Oct 04 20:12:02 volumio2 volumio[16099]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 sudo[16268]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 04 20:12:02 volumio2 volumio[16099]: LoungeScreenDisconnected { Oct 04 20:12:02 volumio2 volumio[16099]: AID: null, Oct 04 20:12:02 volumio2 volumio[16099]: name: 'loungeScreenDisconnected', Oct 04 20:12:02 volumio2 volumio[16099]: payload: {} Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:02 volumio2 volumio[16099]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:02 volumio2 volumio[16099]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: error: [ytcr] Failed to start plugin: Oct 04 20:12:02 volumio2 volumio[16099]: (AppError) Failed to start YouTubeApp Oct 04 20:12:02 volumio2 volumio[16099]: --->(SessionError) (YouTube) Failed to establish session Oct 04 20:12:02 volumio2 volumio[16099]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { Oct 04 20:12:02 volumio2 volumio[16099]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: AppError: Failed to start YouTubeApp Oct 04 20:12:02 volumio2 volumio[16099]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:02 volumio2 volumio[16099]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 04 20:12:02 volumio2 volumio[16099]: LoungeScreenDisconnected { Oct 04 20:12:02 volumio2 volumio[16099]: AID: null, Oct 04 20:12:02 volumio2 volumio[16099]: name: 'loungeScreenDisconnected', Oct 04 20:12:02 volumio2 volumio[16099]: payload: {} Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:02 volumio2 volumio[16099]: Stack trace: Oct 04 20:12:02 volumio2 volumio[16099]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:02 volumio2 volumio[16099]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:02 volumio2 volumio[16099]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:02 volumio2 volumio[16099]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:02 volumio2 volumio[16099]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:02 volumio2 volumio[16099]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:02 volumio2 volumio[16099]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 04 20:12:02 volumio2 volumio-remote-updater[748]: [2024-10-04 20:12:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728061922 101 Oct 04 20:12:02 volumio2 volumio[16099]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Oct 04 20:12:02 volumio2 volumio[16099]: info: VolumeController:: Volume=100 Mute =false Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::volumioGetState Oct 04 20:12:02 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreStateMachine::pushState Oct 04 20:12:02 volumio2 volumio[16099]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:02 volumio2 volumio[16099]: info: [squeezelite_mc] Server discovery started Oct 04 20:12:02 volumio2 volumio[16099]: info: [squeezelite_mc] Player finder started Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:02 volumio2 volumio[16099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:02 volumio2 volumio[16099]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 04 20:12:02 volumio2 volumio[16099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:12:02 volumio2 volumio[16099]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 04 20:12:02 volumio2 volumio[16099]: at doSend (dgram.js:714:16) Oct 04 20:12:02 volumio2 volumio[16099]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Oct 04 20:12:02 volumio2 volumio[16099]: at afterDns (dgram.js:660:5) Oct 04 20:12:02 volumio2 volumio[16099]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Oct 04 20:12:02 volumio2 volumio[16099]: errno: -101, Oct 04 20:12:02 volumio2 volumio[16099]: code: 'ENETUNREACH', Oct 04 20:12:02 volumio2 volumio[16099]: syscall: 'send', Oct 04 20:12:02 volumio2 volumio[16099]: address: '255.255.255.255', Oct 04 20:12:02 volumio2 volumio[16099]: port: 3483 Oct 04 20:12:02 volumio2 volumio[16099]: } Oct 04 20:12:02 volumio2 volumio[16099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. Oct 04 20:12:03 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[16280]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:03 volumio2 systemd[16280]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:03 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:03 volumio2 sudo[16282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 20:11 Oct 04 20:12:03 volumio2 sudo[16282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:03 volumio2 sudo[16282]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:03 volumio2 volumio-remote-updater[748]: [2024-10-04 20:12:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 04 20:12:03 volumio2 volumio-remote-updater[748]: [2024-10-04 20:12:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 04 20:12:03 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 2. Oct 04 20:12:03 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[16289]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:03 volumio2 systemd[16289]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:03 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:03 volumio2 systemd[1]: Started dynamicswap service. Oct 04 20:12:03 volumio2 systemd[1]: dynamicswap.service: Succeeded. Oct 04 20:12:03 volumio2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:03 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 3. Oct 04 20:12:03 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[16296]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:03 volumio2 systemd[16296]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:03 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[1]: Started dynamicswap service. Oct 04 20:12:03 volumio2 systemd[1]: Stopped Volumio Backend Module. Oct 04 20:12:03 volumio2 systemd[1]: Started Volumio Backend Module. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:03 volumio2 systemd[1]: dynamicswap.service: Succeeded. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 4. Oct 04 20:12:03 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:03 volumio2 systemd[16310]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:03 volumio2 systemd[16310]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:03 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:04 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:04 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 5. Oct 04 20:12:04 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:04 volumio2 systemd[1]: squeezelite.service: Start request repeated too quickly. Oct 04 20:12:04 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:04 volumio2 systemd[1]: Failed to start Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:04 volumio2 volumio[16298]: info: ------------------------------------------- Oct 04 20:12:04 volumio2 volumio[16298]: info: ----- Volumio3 ---- Oct 04 20:12:04 volumio2 volumio[16298]: info: ------------------------------------------- Oct 04 20:12:04 volumio2 volumio[16298]: info: ----- System startup ---- Oct 04 20:12:04 volumio2 volumio[16298]: info: ------------------------------------------- Oct 04 20:12:05 volumio2 volumio[16298]: info: MYVOLUMIO Environment detected Oct 04 20:12:05 volumio2 volumio[16298]: info: Plugin folders cleanup Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning into folder /volumio/app/plugins/ Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category audio_interface Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category miscellanea Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category music_service Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category plugins.json Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category system_controller Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category user_interface Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning into folder /data/plugins/ Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category audio_interface Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category music_service Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category system_controller Oct 04 20:12:05 volumio2 volumio[16298]: info: Scanning category user_interface Oct 04 20:12:05 volumio2 volumio[16298]: info: Plugin folders cleanup completed Oct 04 20:12:05 volumio2 volumio[16298]: info: ------------------------------------------- Oct 04 20:12:05 volumio2 volumio[16298]: info: ----- Core plugins startup ---- Oct 04 20:12:05 volumio2 volumio[16298]: info: ------------------------------------------- Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugins from folder /volumio/app/plugins/ Oct 04 20:12:05 volumio2 volumio[16298]: info: Adding plugin upnp to MyMusic Plugins Oct 04 20:12:05 volumio2 volumio[16298]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 04 20:12:05 volumio2 volumio[16298]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugins from folder /data/plugins/ Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "system"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "appearance"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "network"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Refreshing Cached IP Addresses Oct 04 20:12:05 volumio2 sudo[16326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 20:12:05 volumio2 sudo[16326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:05 volumio2 sudo[16328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 20:12:05 volumio2 sudo[16328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "services"... Oct 04 20:12:05 volumio2 sudo[16326]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:05 volumio2 sudo[16328]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "alsa_controller"... Oct 04 20:12:05 volumio2 sudo[16332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 04 20:12:05 volumio2 sudo[16332]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:05 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "wizard"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "networkfs"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Starting Udev Watcher for removable devices Oct 04 20:12:05 volumio2 volumio[16298]: info: Ignoring mount for partition: boot Oct 04 20:12:05 volumio2 volumio[16298]: info: Ignoring mount for partition: volumio Oct 04 20:12:05 volumio2 volumio[16298]: info: Ignoring mount for partition: volumio_data Oct 04 20:12:05 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "volumio_command_line_client"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "upnp"... Oct 04 20:12:05 volumio2 volumio[16298]: info: [1728061925832] Starting Upmpd Daemon Oct 04 20:12:05 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "my_music"... Oct 04 20:12:05 volumio2 volumio[16298]: info: Loading plugin "mpd"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "upnp_browser"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "alarm-clock"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "airplay_emulation"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Starting Shairport Sync Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "last_100"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "webradio"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "i2s_dacs"... Oct 04 20:12:06 volumio2 volumio[16298]: info: I2S DAC not set, start Auto-detection Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "volumiodiscovery"... Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** For more information see Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:12:06 volumio2 volumio[16298]: *** WARNING *** For more information see Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** For more information see Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 04 20:12:06 volumio2 node[16298]: *** WARNING *** For more information see Oct 04 20:12:06 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 04 20:12:06 volumio2 volumio[16298]: info: Discovery: Started advertising with name: Volumio2 Oct 04 20:12:06 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "jellyfin"... Oct 04 20:12:06 volumio2 volumio[16298]: info: Loading plugin "jellyfin_server"... Oct 04 20:12:07 volumio2 volumio[16298]: info: Loading plugin "spop"... Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp: lookup ap-gew1.spotify.com: device or resource busy), retrying with a different AP" Oct 04 20:12:07 volumio2 go-librespot[19308]: time="2024-10-04T20:12:07+03:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Oct 04 20:12:07 volumio2 volumio[16298]: info: Loading plugin "squeezelite_mc"... Oct 04 20:12:08 volumio2 volumio[16298]: info: Loading plugin "youtube2"... Oct 04 20:12:08 volumio2 volumio-remote-updater[748]: [2024-10-04 20:12:08] [connect] Successful connection Oct 04 20:12:08 volumio2 volumio[16298]: info: Loading plugin "ytcr"... Oct 04 20:12:09 volumio2 sudo[16332]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:09 volumio2 go-librespot[19308]: time="2024-10-04T20:12:09+03:00" level=debug msg="renewing login5 access token" Oct 04 20:12:09 volumio2 volumio[16298]: info: Loading plugin "ytmusic"... Oct 04 20:12:10 volumio2 volumio[16298]: info: Loading plugin "now_playing"... Oct 04 20:12:10 volumio2 kernel: wlan0: authenticate with d0:ee:07:61:ac:86 Oct 04 20:12:10 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="WIFI5" Oct 04 20:12:10 volumio2 wpa_supplicant[1051]: wlan0: SME: Trying to authenticate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "outputs"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "albumart"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Plugin example_plugin is not enabled Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "inputs"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "updater_comm"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Plugin mpdemulation is not enabled Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "rest_api"... Oct 04 20:12:11 volumio2 volumio[16298]: Forking 1 albumart workers Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "websocket"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Starting Socket.io Server version 2.3.0 Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "fusiondsp"... Oct 04 20:12:11 volumio2 wpa_supplicant[1051]: wlan0: Trying to associate with d0:ee:07:61:ac:86 (SSID='WIFI5' freq=5200 MHz) Oct 04 20:12:11 volumio2 kernel: wlan0: send auth to d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:12:11 volumio2 kernel: wlan0: authenticated Oct 04 20:12:11 volumio2 kernel: wlan0: associate with d0:ee:07:61:ac:86 (try 1/3) Oct 04 20:12:11 volumio2 wpa_supplicant[1051]: wlan0: Associated with d0:ee:07:61:ac:86 Oct 04 20:12:11 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 04 20:12:11 volumio2 kernel: wlan0: RX AssocResp from d0:ee:07:61:ac:86 (capab=0x831 status=0 aid=2) Oct 04 20:12:11 volumio2 kernel: wlan0: associated Oct 04 20:12:11 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin fusiondsp Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "RoonBridge"... Oct 04 20:12:11 volumio2 volumio[16298]: Starting albumart workers Oct 04 20:12:11 volumio2 wpa_supplicant[1051]: wlan0: WPA: Key negotiation completed with d0:ee:07:61:ac:86 [PTK=CCMP GTK=CCMP] Oct 04 20:12:11 volumio2 wpa_supplicant[1051]: wlan0: CTRL-EVENT-CONNECTED - Connection to d0:ee:07:61:ac:86 completed [id=0 id_str=] Oct 04 20:12:11 volumio2 dhcpcd[834]: wlan0: carrier acquired Oct 04 20:12:11 volumio2 dhcpcd[834]: wlan0: IAID 24:d6:1b:da Oct 04 20:12:11 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin RoonBridge Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "minidlna"... Oct 04 20:12:11 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin minidlna Oct 04 20:12:11 volumio2 volumio[16298]: info: Loading plugin "plexamp"... Oct 04 20:12:11 volumio2 dhcpcd[834]: wlan0: soliciting an IPv6 router Oct 04 20:12:12 volumio2 dhcpcd[834]: wlan0: rebinding lease of 192.168.1.51 Oct 04 20:12:12 volumio2 dhcpcd[834]: wlan0: probing address 192.168.1.51/24 Oct 04 20:12:12 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin plexamp Oct 04 20:12:12 volumio2 volumio[16298]: info: Loading plugin "squeezelite"... Oct 04 20:12:12 volumio2 volumio[16298]: info: [Squeezelite] Initiated plugin Oct 04 20:12:12 volumio2 volumio[16298]: info: Loading plugin "volroon"... Oct 04 20:12:12 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin volroon Oct 04 20:12:12 volumio2 volumio[16298]: info: Loading plugin "volusonic"... Oct 04 20:12:13 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin volusonic Oct 04 20:12:13 volumio2 volumio[16298]: info: Loading plugin "backup_restore"... Oct 04 20:12:13 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin backup_restore Oct 04 20:12:13 volumio2 volumio[16298]: info: Loading plugin "Systeminfo"... Oct 04 20:12:13 volumio2 volumio[16298]: info: Loading plugin "peppymeterbasic"... Oct 04 20:12:13 volumio2 volumio[16298]: info: Loading plugin "peppyspectrum"... Oct 04 20:12:13 volumio2 volumio[16298]: info: Loading plugin "randomizer"... Oct 04 20:12:14 volumio2 volumio[16298]: info: Applying required configuration parameters for plugin randomizer Oct 04 20:12:14 volumio2 volumio[16298]: info: Loading i18n strings for locale ua Oct 04 20:12:14 volumio2 volumio[16298]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:14 volumio2 volumio[16298]: Updating browse sources language Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::initPlayerControls Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: Express server listening on port 3000 Oct 04 20:12:14 volumio2 volumio[16298]: [Metrics] WebUI: 9s 954.31ms Oct 04 20:12:14 volumio2 volumio[16298]: info: Setting Device type: x86 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::resetVolumioState Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::getcurrentVolume Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 04 20:12:14 volumio2 volumio[16298]: info: Completed loading Core Plugins Oct 04 20:12:14 volumio2 volumio[16298]: info: Preparing to generate the ALSA configuration file Oct 04 20:12:14 volumio2 volumio[16298]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 04 20:12:14 volumio2 volumio[16298]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 04 20:12:14 volumio2 volumio[16298]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:14 volumio2 volumio[16298]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:14 volumio2 volumio[16298]: info: Reading ALSA contributions from plugins. Oct 04 20:12:14 volumio2 volumio[16298]: info: Reloading queue from file Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::setRepeat null single undefined Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::pushState Oct 04 20:12:14 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::setRandom null Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::pushState Oct 04 20:12:14 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:14 volumio2 volumio[16298]: info: VolumeController:: Volume=100 Mute =false Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::pushState Oct 04 20:12:14 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreStateMachine::updateTrackBlock Oct 04 20:12:14 volumio2 volumio[16298]: info: CorePlayQueue::getTrackBlock Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:14 volumio2 volumio[16298]: info: Asound.conf file unchanged, so no further update is needed Oct 04 20:12:14 volumio2 volumio[16298]: info: Output device has changed, restarting MPD Oct 04 20:12:14 volumio2 sudo[16375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 04 20:12:14 volumio2 sudo[16375]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 sudo[16375]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:14 volumio2 volumio[16298]: info: Output device has changed, restarting Shairport Sync Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:14 volumio2 sudo[16378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 04 20:12:14 volumio2 sudo[16378]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 systemd[1]: Stopping Music Player Daemon... Oct 04 20:12:14 volumio2 systemd[1]: mpd.service: Succeeded. Oct 04 20:12:14 volumio2 systemd[1]: Stopped Music Player Daemon. Oct 04 20:12:14 volumio2 volumio[16298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 04 20:12:14 volumio2 systemd[1]: Starting Music Player Daemon... Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: volroon::Output device has changed Oct 04 20:12:14 volumio2 volumio[16298]: info: ___________ START PLUGINS ___________ Oct 04 20:12:14 volumio2 volumio[16298]: info: ControllerMpd::onStart: Initializing MPD Oct 04 20:12:14 volumio2 volumio[16298]: info: Creating MPD Configuration file Oct 04 20:12:14 volumio2 sudo[16383]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 04 20:12:14 volumio2 sudo[16383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 sudo[16383]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:14 volumio2 sudo[16389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 04 20:12:14 volumio2 sudo[16389]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 sudo[16389]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:14 volumio2 volumio[16298]: info: [1728061934794] CoreMusicLibrary::Adding element Media Servers Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:14 volumio2 sudo[16392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 04 20:12:14 volumio2 sudo[16392]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 04 20:12:14 volumio2 systemd[1]: mpd.service: Succeeded. Oct 04 20:12:14 volumio2 systemd[1]: Stopped Music Player Daemon. Oct 04 20:12:14 volumio2 volumio[16298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:14 volumio2 systemd[1]: Starting Music Player Daemon... Oct 04 20:12:14 volumio2 volumio[16298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:14 volumio2 volumio[16298]: info: [1728061934927] CoreMusicLibrary::Adding element Last_100 Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:14 volumio2 sudo[16397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:14 volumio2 volumio[16298]: info: [1728061934929] CoreMusicLibrary::Adding element Webradio Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:14 volumio2 sudo[16397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:12:14 volumio2 sudo[16397]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:14 volumio2 volumio[16298]: info: Initializing BBC Radios Oct 04 20:12:14 volumio2 volumio[16298]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 04 20:12:14 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 20:12:15 volumio2 volumio[16298]: info: Discovery: Getting this device information Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::volumioGetState Oct 04 20:12:15 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:15 volumio2 volumio[16298]: info: [1728061935074] CoreMusicLibrary::Adding element Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: info: [jellyfin] Initialized plugin with device info: {"id":"01a5e55c-dda8-49b3-ab3b-5c5bd763a7d0","host":"http://127.0.0.1","name":"Volumio2","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"ATB","track":"My Saving Grace","albumart":"http://127.0.0.1/albumart?cacheid=153&web=ATB/Future%20Memories/extralarge&path=%2FINTERNAL%2F%D0%9C%D1%83%D0%B7%D1%8B%D0%BA%D0%B0%2FAtb%2FATB%20-%20Future%20Memories&metadata=false"}} Oct 04 20:12:15 volumio2 volumio[16298]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Oct 04 20:12:15 volumio2 volumio[16298]: info: Creating Spotify config file Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:15 volumio2 sudo[16414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Oct 04 20:12:15 volumio2 sudo[16414]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:15 volumio2 sudo[16414]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:15 volumio2 volumio[16298]: info: [squeezelite_mc] Starting proxy server... Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:15 volumio2 volumio[16298]: info: [1728061935215] CoreMusicLibrary::Adding element YouTube2 Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:15 volumio2 volumio[16298]: info: [1728061935256] CoreMusicLibrary::Adding element YouTube Music Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:15 volumio2 volumio[16298]: info: [now-playing] ConfigUpdater: config is up to date. Oct 04 20:12:15 volumio2 volumio[16298]: info: Loading i18n strings for locale ua Oct 04 20:12:15 volumio2 volumio[16298]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:15 volumio2 volumio[16298]: info: FusionDsp - mixtype--------------------- Hardware Oct 04 20:12:15 volumio2 volumio[16298]: info: Loading i18n strings for locale ua Oct 04 20:12:15 volumio2 volumio[16298]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 sudo[16433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 04 20:12:15 volumio2 sudo[16433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:15 volumio2 sudo[16433]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:15 volumio2 volumio[16298]: info: Loading i18n strings for locale ua Oct 04 20:12:15 volumio2 volumio[16298]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: Updating browse sources language Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:15 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:15 volumio2 volumio[16298]: info: No Token - please link Plex Account Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Oct 04 20:12:15 volumio2 sudo[16469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 04 20:12:15 volumio2 sudo[16469]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:15 volumio2 systemd[1]: Reloading. Oct 04 20:12:15 volumio2 mpd[16402]: Oct 04 20:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 04 20:12:15 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:15 volumio2 sudo[16482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 04 20:12:15 volumio2 sudo[16482]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:15 volumio2 volumio[16298]: info: volroon::Roon API Services Started Oct 04 20:12:15 volumio2 volumio[16298]: info: Loading i18n strings for locale ua Oct 04 20:12:15 volumio2 volumio[16298]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin plexamp has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:15 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LOCALSETTINGS. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SCREENSIZE_DOC. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key NBARCHANGE. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key DOWNLOAD. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: info: Plugin peppyspectrum has duplicated i18n key LIST_FAIL_UPDATE. It is ignored. Oct 04 20:12:16 volumio2 volumio[16298]: Updating browse sources language Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:16 volumio2 volumio[16298]: info: [1728061936038] CoreMusicLibrary::Adding element Volusonic Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Volusonic Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 20:12:16 volumio2 volumio[16298]: info: [1728061936113] CoreMusicLibrary::Adding element Randomizer Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Jellyfin Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube2 Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source YouTube Music Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Volusonic Oct 04 20:12:16 volumio2 volumio[16298]: Cannot find translation for source Randomizer Oct 04 20:12:16 volumio2 volumio[16298]: info: Volumio Calling Home Oct 04 20:12:16 volumio2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 04 20:12:16 volumio2 volumio[16298]: info: [squeezelite_mc] Proxy server started on port 46123 Oct 04 20:12:16 volumio2 volumio[16298]: info: Preparing to generate the ALSA configuration file Oct 04 20:12:16 volumio2 volumio[16298]: info: PlexAmp::Plex failed to connect Oct 04 20:12:16 volumio2 systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::volumioRetrievevolume Oct 04 20:12:16 volumio2 volumio[16298]: info: [now-playing] App is listening on port 4004. Oct 04 20:12:16 volumio2 volumio[16298]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 04 20:12:16 volumio2 volumio[16298]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline Oct 04 20:12:16 volumio2 volumio[16298]: Unhandled rejection Error: No sockets available, cannot start. Oct 04 20:12:16 volumio2 volumio[16298]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 04 20:12:16 volumio2 volumio[16298]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 04 20:12:16 volumio2 volumio[16298]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 04 20:12:16 volumio2 volumio[16298]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 04 20:12:16 volumio2 volumio[16298]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 04 20:12:16 volumio2 volumio[16298]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 04 20:12:16 volumio2 volumio[16298]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 04 20:12:16 volumio2 volumio[16298]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 04 20:12:16 volumio2 volumio[16298]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 04 20:12:16 volumio2 volumio[16298]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 04 20:12:16 volumio2 volumio[16298]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 04 20:12:16 volumio2 volumio[16298]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 04 20:12:16 volumio2 volumio[16298]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 04 20:12:16 volumio2 volumio[16298]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 04 20:12:16 volumio2 volumio[16298]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 04 20:12:16 volumio2 volumio[16298]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 04 20:12:16 volumio2 systemd[1]: Started Music Player Daemon. Oct 04 20:12:16 volumio2 sudo[16469]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 sudo[16392]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 sudo[16378]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 sudo[16482]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 sudo[16497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 20:12:16 volumio2 sudo[16497]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:16 volumio2 sudo[16497]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 volumio[16298]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Oct 04 20:12:16 volumio2 sudo[16502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 20:12:16 volumio2 sudo[16502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:16 volumio2 volumio[16298]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 04 20:12:16 volumio2 volumio[16298]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:16 volumio2 volumio[16298]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Oct 04 20:12:16 volumio2 volumio[16298]: info: Reading ALSA contributions from plugins. Oct 04 20:12:16 volumio2 sudo[16502]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (mlb773qfhglf851qrl5k3fqpkq): Oct 04 20:12:16 volumio2 volumio[16298]: (ConnectionError) (YouTube) Connection error in getting lounge token Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { Oct 04 20:12:16 volumio2 volumio[16298]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: ConnectionError: (YouTube) Connection error in getting lounge token Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 04 20:12:16 volumio2 volumio[16298]: at async Promise.all (index 0) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: Going to generate fresh screen Id and try again... Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (je64tvadijgk6g3lkr0fft4m6a): Oct 04 20:12:16 volumio2 volumio[16298]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { Oct 04 20:12:16 volumio2 volumio[16298]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: ConnectionError: (YouTube Music) Connection error in getting lounge token Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Oct 04 20:12:16 volumio2 volumio[16298]: at async Promise.all (index 1) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: Going to generate fresh screen Id and try again... Oct 04 20:12:16 volumio2 volumio[16298]: info: ::Roon Bridge has successfully started Oct 04 20:12:16 volumio2 volumio[16298]: info: squeezelite reloaded Oct 04 20:12:16 volumio2 volumio[16298]: info: minidlna: Found miniDLNA version 1.2.1 Oct 04 20:12:16 volumio2 volumio[16298]: info: minidlna: Starting minidlna.service Oct 04 20:12:16 volumio2 sudo[16507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Oct 04 20:12:16 volumio2 sudo[16507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:16 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:16 volumio2 systemd[16510]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:16 volumio2 systemd[16510]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:16 volumio2 sudo[16507]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 sudo[16511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 04 20:12:16 volumio2 sudo[16511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:16 volumio2 sudo[16511]: pam_unix(sudo:session): session closed for user root Oct 04 20:12:16 volumio2 volumio[16298]: info: MPD Permissions set Oct 04 20:12:16 volumio2 volumio[16298]: info: MPD Permissions set Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 04 20:12:16 volumio2 volumio[16298]: LoungeScreenDisconnected { Oct 04 20:12:16 volumio2 volumio[16298]: AID: null, Oct 04 20:12:16 volumio2 volumio[16298]: name: 'loungeScreenDisconnected', Oct 04 20:12:16 volumio2 volumio[16298]: payload: {} Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:16 volumio2 volumio[16298]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 04 20:12:16 volumio2 volumio[16298]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 04 20:12:16 volumio2 volumio[16298]: at new Promise () Oct 04 20:12:16 volumio2 volumio[16298]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 04 20:12:16 volumio2 volumio[16298]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 04 20:12:16 volumio2 volumio[16298]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async Promise.all (index 0) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] Failed to start receiver: Oct 04 20:12:16 volumio2 volumio[16298]: (AppError) Failed to start YouTubeApp Oct 04 20:12:16 volumio2 volumio[16298]: --->(SessionError) (YouTube) Failed to establish session Oct 04 20:12:16 volumio2 volumio[16298]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { Oct 04 20:12:16 volumio2 volumio[16298]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: AppError: Failed to start YouTubeApp Oct 04 20:12:16 volumio2 volumio[16298]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 04 20:12:16 volumio2 volumio[16298]: LoungeScreenDisconnected { Oct 04 20:12:16 volumio2 volumio[16298]: AID: null, Oct 04 20:12:16 volumio2 volumio[16298]: name: 'loungeScreenDisconnected', Oct 04 20:12:16 volumio2 volumio[16298]: payload: {} Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:16 volumio2 volumio[16298]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 04 20:12:16 volumio2 volumio[16298]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 04 20:12:16 volumio2 volumio[16298]: at new Promise () Oct 04 20:12:16 volumio2 volumio[16298]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 04 20:12:16 volumio2 volumio[16298]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 04 20:12:16 volumio2 volumio[16298]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Oct 04 20:12:16 volumio2 volumio[16298]: at Array.forEach () Oct 04 20:12:16 volumio2 volumio[16298]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 04 20:12:16 volumio2 volumio[16298]: LoungeScreenDisconnected { Oct 04 20:12:16 volumio2 volumio[16298]: AID: null, Oct 04 20:12:16 volumio2 volumio[16298]: name: 'loungeScreenDisconnected', Oct 04 20:12:16 volumio2 volumio[16298]: payload: {} Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:16 volumio2 volumio[16298]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:16 volumio2 volumio[16298]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: error: [ytcr] Failed to start plugin: Oct 04 20:12:16 volumio2 volumio[16298]: (AppError) Failed to start YouTubeApp Oct 04 20:12:16 volumio2 volumio[16298]: --->(SessionError) (YouTube) Failed to establish session Oct 04 20:12:16 volumio2 volumio[16298]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { Oct 04 20:12:16 volumio2 volumio[16298]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: AppError: Failed to start YouTubeApp Oct 04 20:12:16 volumio2 volumio[16298]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 04 20:12:16 volumio2 volumio[16298]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 04 20:12:16 volumio2 volumio[16298]: LoungeScreenDisconnected { Oct 04 20:12:16 volumio2 volumio[16298]: AID: null, Oct 04 20:12:16 volumio2 volumio[16298]: name: 'loungeScreenDisconnected', Oct 04 20:12:16 volumio2 volumio[16298]: payload: {} Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 04 20:12:16 volumio2 volumio[16298]: Stack trace: Oct 04 20:12:16 volumio2 volumio[16298]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 04 20:12:16 volumio2 volumio[16298]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 04 20:12:16 volumio2 volumio[16298]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 04 20:12:16 volumio2 volumio[16298]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 04 20:12:16 volumio2 volumio[16298]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 04 20:12:16 volumio2 volumio[16298]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 04 20:12:16 volumio2 volumio[16298]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 04 20:12:16 volumio2 volumio[16298]: info: MPD running with PID16402 Oct 04 20:12:16 volumio2 volumio[16298]: ,establishing connection Oct 04 20:12:16 volumio2 volumio[16298]: info: minidlna: systemctl start minidlna.service succeeded. Oct 04 20:12:16 volumio2 volumio[16298]: info: squeezelite started Oct 04 20:12:16 volumio2 volumio[16298]: info: VolumeController:: Volume=100 Mute =false Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::volumioGetState Oct 04 20:12:16 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreStateMachine::pushState Oct 04 20:12:16 volumio2 volumio[16298]: info: CorePlayQueue::getTrack 0 Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::volumioPushState Oct 04 20:12:16 volumio2 volumio[16298]: info: [squeezelite_mc] Server discovery started Oct 04 20:12:16 volumio2 volumio[16298]: info: [squeezelite_mc] Player finder started Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 20:12:16 volumio2 volumio[16298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 04 20:12:16 volumio2 volumio[16298]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 04 20:12:16 volumio2 volumio[16298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:12:16 volumio2 volumio[16298]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 04 20:12:16 volumio2 volumio[16298]: at doSend (dgram.js:714:16) Oct 04 20:12:16 volumio2 volumio[16298]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Oct 04 20:12:16 volumio2 volumio[16298]: at afterDns (dgram.js:660:5) Oct 04 20:12:16 volumio2 volumio[16298]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Oct 04 20:12:16 volumio2 volumio[16298]: errno: -101, Oct 04 20:12:16 volumio2 volumio[16298]: code: 'ENETUNREACH', Oct 04 20:12:16 volumio2 volumio[16298]: syscall: 'send', Oct 04 20:12:16 volumio2 volumio[16298]: address: '255.255.255.255', Oct 04 20:12:16 volumio2 volumio[16298]: port: 3483 Oct 04 20:12:16 volumio2 volumio[16298]: } Oct 04 20:12:16 volumio2 volumio[16298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. Oct 04 20:12:16 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:16 volumio2 dhcpcd[834]: wlan0: leased 192.168.1.51 for 86400 seconds Oct 04 20:12:16 volumio2 dhcpcd[834]: wlan0: adding route to 192.168.1.0/24 Oct 04 20:12:16 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:16 volumio2 dhcpcd[834]: wlan0: adding default route via 192.168.1.1 Oct 04 20:12:16 volumio2 avahi-daemon[749]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.51. Oct 04 20:12:16 volumio2 avahi-daemon[749]: New relevant interface wlan0.IPv4 for mDNS. Oct 04 20:12:16 volumio2 systemd[16526]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:16 volumio2 avahi-daemon[749]: Registering new address record for 192.168.1.51 on wlan0.IPv4. Oct 04 20:12:16 volumio2 systemd[16526]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:16 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:17 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Oct 04 20:12:17 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 2. Oct 04 20:12:17 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:17 volumio2 systemd[16557]: squeezelite.service: Failed to execute command: Permission denied Oct 04 20:12:17 volumio2 systemd[16557]: squeezelite.service: Failed at step EXEC spawning /opt/squeezelite: Permission denied Oct 04 20:12:17 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 04 20:12:17 volumio2 systemd[1]: squeezelite.service: Main process exited, code=exited, status=203/EXEC Oct 04 20:12:17 volumio2 systemd[1]: squeezelite.service: Failed with result 'exit-code'. Oct 04 20:12:17 volumio2 ntpd[9242]: ntpd exiting on signal 15 (Terminated) Oct 04 20:12:17 volumio2 systemd[1]: Stopping Network Time Service... Oct 04 20:12:17 volumio2 systemd[1]: ntp.service: Succeeded. Oct 04 20:12:17 volumio2 systemd[1]: Stopped Network Time Service. Oct 04 20:12:17 volumio2 systemd[1]: Starting Network Time Service... Oct 04 20:12:17 volumio2 ntpd[16561]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 04 20:12:17 volumio2 ntpd[16561]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 04 20:12:17 volumio2 systemd[1]: Started Network Time Service. Oct 04 20:12:17 volumio2 sudo[16568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 20:11 Oct 04 20:12:17 volumio2 sudo[16568]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 20:12:17 volumio2 ntpd[16570]: proto: precision = 0.097 usec (-23) Oct 04 20:12:17 volumio2 ntpd[16570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 04 20:12:17 volumio2 ntpd[16570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 04 20:12:17 volumio2 ntpd[16570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 647 days ago Oct 04 20:12:17 volumio2 ntpd[16570]: Listen and drop on 0 v6wildcard [::]:123 Oct 04 20:12:17 volumio2 ntpd[16570]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 04 20:12:17 volumio2 ntpd[16570]: Listen normally on 2 lo 127.0.0.1:123 Oct 04 20:12:17 volumio2 ntpd[16570]: Listen normally on 3 wlan0 192.168.1.51:123 Oct 04 20:12:17 volumio2 ntpd[16570]: Listening on routing socket on fd #20 for interface updates Oct 04 20:12:17 volumio2 ntpd[16570]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 04 20:12:17 volumio2 ntpd[16570]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"