-- Logs begin at Wed 2024-10-02 00:15:19 EDT, end at Sat 2024-10-26 09:00:28 EDT. -- Oct 26 08:59:02 volumio-living volumio[1749]: info: Discovery: adding d7f7d63e-3ac3-4d8b-a175-7beac8d6b053 Oct 26 08:59:02 volumio-living volumio[1749]: info: Discovery: Found device Volumio-Living Oct 26 08:59:02 volumio-living volumio[1749]: info: CoreCommandRouter::volumioGetState Oct 26 08:59:02 volumio-living volumio[1749]: info: CorePlayQueue::getTrack 0 Oct 26 08:59:04 volumio-living volumio[1749]: info: Discovery: adding a6c0b88c-f8eb-4abe-9736-907f00d016d3 Oct 26 08:59:04 volumio-living volumio[1749]: info: Discovery: Found device Volumio-Bedroom Oct 26 08:59:04 volumio-living volumio[1749]: info: Discovery: Connecting to remote: 192.168.3.67 Oct 26 08:59:04 volumio-living volumio[1749]: info: Discovery: Connected to remote: 192.168.3.67 Oct 26 08:59:07 volumio-living volumio[1749]: error: MyVolumio Custom Token format not valid, refreshing it Oct 26 08:59:10 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 08:59:14 volumio-living volumio[1749]: verbose: New Socket.io Connection to 192.168.3.65:3000 from 192.168.3.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 26 08:59:14 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 26 08:59:14 volumio-living volumio[1749]: info: CoreCommandRouter::volumioGetState Oct 26 08:59:14 volumio-living volumio[1749]: info: CorePlayQueue::getTrack 0 Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 08:59:15 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 08:59:17 volumio-living volumio[1749]: info: Discovery: adding dc8c9251-3a0a-4ae7-bee2-b890c2987eb3 Oct 26 08:59:17 volumio-living volumio[1749]: info: Discovery: Found device Volumio-Office Oct 26 08:59:17 volumio-living volumio[1749]: info: Discovery: Connecting to remote: 192.168.3.66 Oct 26 08:59:17 volumio-living volumio[1749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 26 08:59:17 volumio-living volumio[1749]: info: Discovery: Connected to remote: 192.168.3.66 Oct 26 08:59:19 volumio-living volumio[1749]: info: CoreCommandRouter::volumioGetState Oct 26 08:59:19 volumio-living volumio[1749]: info: CorePlayQueue::getTrack 0 Oct 26 08:59:25 volumio-living volumio[1749]: info: MyVolumio login type: Token Oct 26 08:59:29 volumio-living volumio[1749]: info: Discovery: Disconnected from remote: 192.168.3.67 Oct 26 08:59:29 volumio-living volumio[1749]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'disconnect' Oct 26 08:59:29 volumio-living volumio[1749]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'connect' Oct 26 08:59:29 volumio-living volumio[1749]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) Oct 26 08:59:29 volumio-living volumio[1749]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [{"id":"b8:27:eb:80:5a:79","uuid":null,"ip":"192.168.3.65","name":"lms-living","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"00:04:20:2a:6e:11","uuid":"8ba1918f8623eeee2ca52da24a4c5d74","ip":"192.168.0.12","name":"Squeezebox Radio","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:53:22:24","uuid":null,"ip":"192.168.3.66","name":"lms-office","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}}] Oct 26 08:59:34 volumio-living volumio[1749]: info: Discovery: Connected to remote: 192.168.3.67 Oct 26 08:59:35 volumio-living volumio[1749]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 26 08:59:37 volumio-living volumio[1749]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'disconnect' Oct 26 08:59:37 volumio-living volumio[1749]: info: [squeezelite_mc] 'client' notification received from pCP-LMS (192.168.3.61); type is 'connect' Oct 26 08:59:37 volumio-living volumio[1749]: info: [squeezelite_mc] Getting players connected to pCP-LMS (192.168.3.61) Oct 26 08:59:38 volumio-living volumio[1749]: info: [squeezelite_mc] Players connected to pCP-LMS (192.168.3.61): [{"id":"b8:27:eb:80:5a:79","uuid":null,"ip":"192.168.3.65","name":"lms-living","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"00:04:20:2a:6e:11","uuid":"8ba1918f8623eeee2ca52da24a4c5d74","ip":"192.168.0.12","name":"Squeezebox Radio","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:35:96:7a","uuid":null,"ip":"192.168.3.67","name":"lms-bedroom","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:53:22:24","uuid":null,"ip":"192.168.3.66","name":"lms-office","server":{"ip":"192.168.3.61","name":"pCP-LMS","ver":"7.999.999","uuid":"3a9627f6-390a-4012-9749-e98428a5ceae","jsonPort":"9000","cliPort":"9090"}}] Oct 26 08:59:41 volumio-living volumio[1749]: info: MyVolumio token set successfully Oct 26 08:59:41 volumio-living volumio[1749]: info: MYVOLUMIO: Adding device Oct 26 08:59:41 volumio-living volumio[1749]: info: MYVOLUMIO: Evaluating Server Oct 26 08:59:41 volumio-living volumio[1749]: info: MyVolumio status changed Oct 26 08:59:41 volumio-living volumio[1749]: info: Streaming services startup Oct 26 08:59:41 volumio-living volumio[1749]: info: Starting Streaming Daemon Oct 26 08:59:41 volumio-living volumio[1749]: info: Removing browser output: myVolumio user plan is not superstar Oct 26 08:59:41 volumio-living volumio[1749]: info: Removing audio output: Oct 26 08:59:41 volumio-living volumio[1749]: info: Stoppping Tunnel 1 Oct 26 08:59:41 volumio-living sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 08:59:41 volumio-living sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:59:41 volumio-living sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 26 08:59:41 volumio-living sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 08:59:41 volumio-living sudo[2286]: pam_unix(sudo:session): session closed for user root Oct 26 08:59:41 volumio-living volumio[1749]: error: Cannot start Volumio Streaming Daemon Oct 26 08:59:41 volumio-living volumio[1749]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 08:59:41 volumio-living volumio[1749]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 08:59:41 volumio-living sudo[2289]: pam_unix(sudo:session): session closed for user root Oct 26 08:59:41 volumio-living volumio[1749]: info: Remote SSH Stopped Oct 26 08:59:51 volumio-living volumio[1749]: info: Setting Geolocation for MyVolumio to eu2 Oct 26 08:59:51 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 08:59:51 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 08:59:51 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 08:59:53 volumio-living volumio[1749]: info: Updating MyVolumio device info Oct 26 08:59:53 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 08:59:54 volumio-living volumio[1749]: verbose: New Socket.io Connection to 192.168.3.65:3000 from 192.168.3.67 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 26 08:59:54 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 26 08:59:54 volumio-living volumio[1749]: info: CoreCommandRouter::volumioGetState Oct 26 08:59:54 volumio-living volumio[1749]: info: CorePlayQueue::getTrack 0 Oct 26 08:59:57 volumio-living volumio[1749]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 08:59:58 volumio-living volumio[1749]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 09:00:01 volumio-living volumio[1749]: info: MYVOLUMIO: Adding device Oct 26 09:00:01 volumio-living volumio[1749]: info: MYVOLUMIO: Evaluating Server Oct 26 09:00:11 volumio-living volumio[1749]: info: Setting Geolocation for MyVolumio to eu2 Oct 26 09:00:11 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 09:00:12 volumio-living volumio[1749]: info: Updating MyVolumio device info Oct 26 09:00:12 volumio-living volumio[1749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 09:00:16 volumio-living volumio[1749]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 09:00:17 volumio-living wpa_supplicant[854]: RRM: Ignoring radio measurement request: Not RRM network Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: carrier lost Oct 26 09:00:17 volumio-living wpa_supplicant[854]: wlan0: Associated with 3a:de:4b:76:33:8c Oct 26 09:00:17 volumio-living wpa_supplicant[854]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3a:de:4b:76:33:8c completed [id=0 id_str=] Oct 26 09:00:17 volumio-living wpa_supplicant[854]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 26 09:00:17 volumio-living avahi-daemon[579]: Withdrawing address record for 192.168.3.65 on wlan0. Oct 26 09:00:17 volumio-living avahi-daemon[579]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.65. Oct 26 09:00:17 volumio-living avahi-daemon[579]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: deleting route to 192.168.0.0/22 Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: deleting default route via 192.168.0.1 Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: A device disappeared from network Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Device volumio-office disappeared from network Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Disconnected from remote: 192.168.3.66 Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: A device disappeared from network Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Device volumio-living disappeared from network Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: A device disappeared from network Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Device volumio-bedroom disappeared from network Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: carrier acquired Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Disconnected from remote: 192.168.3.67 Oct 26 09:00:17 volumio-living volumio[1749]: info: Discovery: Disconnected from remote: 192.168.3.67 Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: IAID eb:80:5a:79 Oct 26 09:00:17 volumio-living volumio[1749]: error: Failed to update MyVolumio device: undefined Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: soliciting an IPv6 router Oct 26 09:00:17 volumio-living dhcpcd[866]: wlan0: soliciting a DHCP lease Oct 26 09:00:18 volumio-living ntpd[767]: Deleting interface #4 wlan0, 192.168.3.65#123, interface stats: received=72, sent=74, dropped=0, active_time=119 secs Oct 26 09:00:18 volumio-living ntpd[767]: 66.118.231.14 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 12.167.151.1 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 50.218.103.254 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 45.63.54.13 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 23.150.41.122 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 173.71.68.71 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 148.113.194.34 local addr 192.168.3.65 -> Oct 26 09:00:18 volumio-living ntpd[767]: 207.246.65.226 local addr 192.168.3.65 -> Oct 26 09:00:22 volumio-living dhcpcd[866]: wlan0: offered 192.168.3.65 from 192.168.0.1 Oct 26 09:00:22 volumio-living dhcpcd[866]: wlan0: probing address 192.168.3.65/22 Oct 26 09:00:24 volumio-living wpa_supplicant[854]: RRM: Ignoring radio measurement request: Not RRM network Oct 26 09:00:27 volumio-living volumio[1749]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 09:00:27 volumio-living volumio[1749]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 26 09:00:27 volumio-living volumio[1749]: at doSend (dgram.js:692:16) Oct 26 09:00:27 volumio-living volumio[1749]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 26 09:00:27 volumio-living volumio[1749]: at afterDns (dgram.js:638:5) Oct 26 09:00:27 volumio-living volumio[1749]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 26 09:00:27 volumio-living volumio[1749]: errno: -101, Oct 26 09:00:27 volumio-living volumio[1749]: code: 'ENETUNREACH', Oct 26 09:00:27 volumio-living volumio[1749]: syscall: 'send', Oct 26 09:00:27 volumio-living volumio[1749]: address: '255.255.255.255', Oct 26 09:00:27 volumio-living volumio[1749]: port: 3483 Oct 26 09:00:27 volumio-living volumio[1749]: } Oct 26 09:00:27 volumio-living volumio[1749]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 09:00:28 volumio-living dhcpcd[866]: wlan0: leased 192.168.3.65 for 7200 seconds Oct 26 09:00:28 volumio-living avahi-daemon[579]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.65. Oct 26 09:00:28 volumio-living avahi-daemon[579]: New relevant interface wlan0.IPv4 for mDNS. Oct 26 09:00:28 volumio-living dhcpcd[866]: wlan0: adding route to 192.168.0.0/22 Oct 26 09:00:28 volumio-living avahi-daemon[579]: Registering new address record for 192.168.3.65 on wlan0.IPv4. Oct 26 09:00:28 volumio-living dhcpcd[866]: wlan0: adding default route via 192.168.0.1 Oct 26 09:00:28 volumio-living sudo[2407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 08:59 Oct 26 09:00:28 volumio-living sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"