-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Thu 2024-05-09 11:04:00 JST. -- May 09 11:03:00 volumio sudo[29584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 11:03:00 volumio sudo[29584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 11:03:00 volumio systemd[1]: Stopping Volumio Streaming Daemon... May 09 11:03:00 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM May 09 11:03:00 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. May 09 11:03:00 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 09 11:03:00 volumio systemd[1]: Started Volumio Streaming Daemon. May 09 11:03:00 volumio sudo[29584]: pam_unix(sudo:session): session closed for user root May 09 11:03:00 volumio volumio-streaming-daemon[29592]: ############################ May 09 11:03:00 volumio volumio-streaming-daemon[29592]: # Volumio Streaming Daemon # May 09 11:03:00 volumio volumio-streaming-daemon[29592]: # Running on port 7777 # May 09 11:03:00 volumio volumio-streaming-daemon[29592]: ############################ May 09 11:03:00 volumio volumio-streaming-daemon[29592]: Environment: production May 09 11:03:01 volumio volumio[29232]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 09 11:03:01 volumio volumio[29232]: info: MYVOLUMIO: Adding device May 09 11:03:01 volumio volumio[29232]: info: MYVOLUMIO: Evaluating Server May 09 11:03:02 volumio volumio-streaming-daemon[29592]: Environment: production May 09 11:03:02 volumio volumio[29232]: info: Fetching Streaming Services browse cache May 09 11:03:03 volumio volumio[29232]: info: MyVolumio status changed May 09 11:03:03 volumio volumio[29232]: info: Streaming services startup May 09 11:03:03 volumio volumio[29232]: info: Re-Starting Streaming Daemon May 09 11:03:03 volumio volumio[29232]: info: Removing browser output: myVolumio user plan is not superstar May 09 11:03:03 volumio volumio[29232]: info: Removing audio output: May 09 11:03:03 volumio volumio[29232]: info: Stoppping Tunnel 1 May 09 11:03:03 volumio volumio-streaming-daemon[29592]: Restarting daemon May 09 11:03:03 volumio volumio-streaming-daemon[29592]: Environment: production May 09 11:03:04 volumio volumio-streaming-daemon[29592]: Environment: production May 09 11:03:04 volumio volumio[29232]: info: Fetching Streaming Services browse cache May 09 11:03:04 volumio volumio[29232]: info: Setting Geolocation for MyVolumio to as1 May 09 11:03:04 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 11:03:04 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 11:03:04 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 11:03:05 volumio sudo[29617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 09 11:03:05 volumio sudo[29617]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 11:03:05 volumio sudo[29617]: pam_unix(sudo:session): session closed for user root May 09 11:03:05 volumio volumio[29232]: info: Remote SSH Stopped May 09 11:03:05 volumio volumio[29232]: info: Updating MyVolumio device info May 09 11:03:05 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 11:03:08 volumio volumio[29232]: info: [squeezelite_mc] 'client' notification received from volumio (192.168.3.7); type is 'connect' May 09 11:03:08 volumio volumio[29232]: info: [squeezelite_mc] Getting players connected to volumio (192.168.3.7) May 09 11:03:08 volumio volumio[29232]: info: [squeezelite_mc] Players connected to volumio (192.168.3.7): [{"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}}] May 09 11:03:08 volumio volumio[29232]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:ba:e8:23","uuid":null,"ip":"192.168.3.7","name":"volumio","server":{"ip":"192.168.3.7","name":"volumio","ver":"8.2.1","uuid":"0898b45c-b024-47df-abd3-3ffd3a18f44f","jsonPort":"9000","cliPort":"9090"}} May 09 11:03:10 volumio volumio[29232]: info: CoreCommandRouter::volumioGetState May 09 11:03:10 volumio volumio[29232]: info: CorePlayQueue::getTrack 0 May 09 11:03:13 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 May 09 11:03:13 volumio dhcpcd[1053]: wlan0: carrier lost May 09 11:03:13 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 09 11:03:13 volumio avahi-daemon[487]: Withdrawing address record for 192.168.3.7 on wlan0. May 09 11:03:13 volumio avahi-daemon[487]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. May 09 11:03:13 volumio avahi-daemon[487]: Interface wlan0.IPv4 no longer relevant for mDNS. May 09 11:03:13 volumio dhcpcd[1053]: wlan0: deleting route to 192.168.3.0/24 May 09 11:03:13 volumio dhcpcd[1053]: wlan0: deleting default route via 192.168.3.1 May 09 11:03:13 volumio volumio[29232]: info: mDNS: A device disapperared from network May 09 11:03:13 volumio volumio[29232]: info: mDNS: Device volumio disapperared from network May 09 11:03:15 volumio ntpd[922]: Deleting interface #30 wlan0, 192.168.3.7#123, interface stats: received=16, sent=17, dropped=0, active_time=18 secs May 09 11:03:15 volumio ntpd[922]: 202.182.111.234 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 129.250.35.250 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 133.243.238.163 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 18.180.64.47 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 202.181.103.212 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 172.105.204.105 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 133.243.238.243 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 160.16.113.133 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 139.162.96.56 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 129.250.35.251 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 162.159.200.123 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 45.77.20.103 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 133.130.121.141 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 162.159.200.1 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 20.210.25.42 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 45.76.221.157 local addr 192.168.3.7 -> May 09 11:03:15 volumio ntpd[922]: 45.76.218.37 local addr 192.168.3.7 -> May 09 11:03:15 volumio wpa_supplicant[1041]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' May 09 11:03:17 volumio dhcpcd[1053]: wlan0: carrier acquired May 09 11:03:17 volumio wpa_supplicant[1041]: wlan0: Associated with a0:95:7f:89:1b:70 May 09 11:03:17 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] May 09 11:03:17 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 09 11:03:17 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 09 11:03:17 volumio dhcpcd[1053]: wlan0: IAID eb:ba:e8:23 May 09 11:03:17 volumio dhcpcd[1053]: wlan0: carrier lost May 09 11:03:17 volumio dhcpcd[1053]: wlan0: carrier acquired May 09 11:03:17 volumio dhcpcd[1053]: wlan0: IAID eb:ba:e8:23 May 09 11:03:17 volumio dhcpcd[1053]: wlan0: soliciting an IPv6 router May 09 11:03:17 volumio dhcpcd[1053]: wlan0: rebinding lease of 192.168.3.7 May 09 11:03:19 volumio volumio[29232]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 09 11:03:19 volumio volumio[29232]: info: Completed starting MyVolumio Plugin May 09 11:03:19 volumio volumio[29232]: info: BOOT COMPLETED May 09 11:03:19 volumio volumio[29232]: [Metrics] CommandRouter: 59s 799.56ms May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::volumiosetStartupVolume May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 11:03:19 volumio volumio[29232]: info: VolumeController:: Setting startup Volume 80 May 09 11:03:19 volumio volumio[29232]: info: VolumeController::SetAlsaVolume80 May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::Close All Modals sent May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::Close All Modals sent May 09 11:03:19 volumio volumio[29232]: info: [squeezelite_mc] Setting Squeezelite volume to 80 May 09 11:03:19 volumio volumio[29232]: info: CoreStateMachine::pushState May 09 11:03:19 volumio volumio[29232]: info: CorePlayQueue::getTrack 0 May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 11:03:19 volumio volumio[29232]: info: CoreCommandRouter::volumioPushState May 09 11:03:19 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 May 09 11:03:19 volumio volumio[29232]: (node:29232) UnhandledPromiseRejectionWarning: FetchError: request to http://192.168.3.7:9000/jsonrpc.js failed, reason: connect ENETUNREACH 192.168.3.7:9000 - Local (0.0.0.0:0) May 09 11:03:19 volumio volumio[29232]: at ClientRequest. (/data/plugins/music_service/squeezelite_mc/node_modules/node-fetch/lib/index.js:1491:11) May 09 11:03:19 volumio volumio[29232]: at ClientRequest.emit (events.js:315:20) May 09 11:03:19 volumio volumio[29232]: at Socket.socketErrorListener (_http_client.js:469:9) May 09 11:03:19 volumio volumio[29232]: at Socket.emit (events.js:315:20) May 09 11:03:19 volumio volumio[29232]: at emitErrorNT (internal/streams/destroy.js:106:8) May 09 11:03:19 volumio volumio[29232]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 09 11:03:19 volumio volumio[29232]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 09 11:03:19 volumio volumio[29232]: (Use `node --trace-warnings ...` to show where the warning was created) May 09 11:03:19 volumio volumio[29232]: (node:29232) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) May 09 11:03:19 volumio volumio[29232]: (node:29232) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. May 09 11:03:20 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 11:03:20 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 11:03:20 volumio volumio[29232]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 11:03:22 volumio dhcpcd[1053]: wlan0: probing address 192.168.3.7/24 May 09 11:03:27 volumio dhcpcd[1053]: wlan0: leased 192.168.3.7 for 86400 seconds May 09 11:03:27 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. May 09 11:03:27 volumio dhcpcd[1053]: wlan0: adding route to 192.168.3.0/24 May 09 11:03:27 volumio dhcpcd[1053]: wlan0: adding default route via 192.168.3.1 May 09 11:03:27 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. May 09 11:03:27 volumio avahi-daemon[487]: Registering new address record for 192.168.3.7 on wlan0.IPv4. May 09 11:03:29 volumio volumio[29232]: info: Discovery: adding 18bcd961-2cad-4825-9d0c-cefd458c8c21 May 09 11:03:29 volumio volumio[29232]: info: mDNS: Found device Volumio May 09 11:03:29 volumio volumio[29232]: info: CoreCommandRouter::volumioGetState May 09 11:03:29 volumio volumio[29232]: info: CorePlayQueue::getTrack 0 May 09 11:03:30 volumio ntpd[922]: Listen normally on 31 wlan0 192.168.3.7:123 May 09 11:03:30 volumio ntpd[922]: new interface(s) found: waking up resolver May 09 11:03:31 volumio ntpd[922]: 202.181.103.212 local addr 192.168.3.7 -> May 09 11:03:31 volumio ntpd[922]: 133.243.238.163 local addr 192.168.3.7 -> May 09 11:03:33 volumio ntpd[922]: 18.180.64.47 local addr 192.168.3.7 -> May 09 11:03:33 volumio ntpd[922]: 129.250.35.250 local addr 192.168.3.7 -> May 09 11:03:33 volumio ntpd[922]: 202.182.111.234 local addr 192.168.3.7 -> May 09 11:03:47 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:95:7f:89:1b:70 reason=0 locally_generated=1 May 09 11:03:47 volumio dhcpcd[1053]: wlan0: carrier lost May 09 11:03:47 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 09 11:03:47 volumio avahi-daemon[487]: Withdrawing address record for 192.168.3.7 on wlan0. May 09 11:03:47 volumio avahi-daemon[487]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. May 09 11:03:47 volumio avahi-daemon[487]: Interface wlan0.IPv4 no longer relevant for mDNS. May 09 11:03:47 volumio volumio[29232]: info: mDNS: A device disapperared from network May 09 11:03:47 volumio volumio[29232]: info: mDNS: Device volumio disapperared from network May 09 11:03:47 volumio dhcpcd[1053]: wlan0: deleting route to 192.168.3.0/24 May 09 11:03:47 volumio dhcpcd[1053]: wlan0: deleting default route via 192.168.3.1 May 09 11:03:48 volumio wpa_supplicant[1041]: wlan0: Trying to associate with SSID 'A0957F891B71-2G' May 09 11:03:48 volumio ntpd[922]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 09 11:03:50 volumio dhcpcd[1053]: wlan0: carrier acquired May 09 11:03:50 volumio ntpd[922]: Deleting interface #31 wlan0, 192.168.3.7#123, interface stats: received=11, sent=12, dropped=0, active_time=20 secs May 09 11:03:50 volumio ntpd[922]: 172.105.204.105 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 133.243.238.243 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 160.16.113.133 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 139.162.96.56 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 129.250.35.251 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 162.159.200.123 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 45.77.20.103 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 133.130.121.141 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 162.159.200.1 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 20.210.25.42 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 45.76.221.157 local addr 192.168.3.7 -> May 09 11:03:50 volumio ntpd[922]: 45.76.218.37 local addr 192.168.3.7 -> May 09 11:03:50 volumio wpa_supplicant[1041]: wlan0: Associated with a0:95:7f:89:1b:70 May 09 11:03:50 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:95:7f:89:1b:70 completed [id=0 id_str=] May 09 11:03:50 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 09 11:03:50 volumio wpa_supplicant[1041]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 09 11:03:50 volumio dhcpcd[1053]: wlan0: IAID eb:ba:e8:23 May 09 11:03:50 volumio dhcpcd[1053]: wlan0: carrier lost May 09 11:03:50 volumio dhcpcd[1053]: wlan0: carrier acquired May 09 11:03:50 volumio dhcpcd[1053]: wlan0: IAID eb:ba:e8:23 May 09 11:03:50 volumio dhcpcd[1053]: wlan0: rebinding lease of 192.168.3.7 May 09 11:03:50 volumio dhcpcd[1053]: wlan0: soliciting an IPv6 router May 09 11:03:54 volumio dhcpcd[1053]: wlan0: probing address 192.168.3.7/24 May 09 11:03:59 volumio dhcpcd[1053]: wlan0: leased 192.168.3.7 for 86400 seconds May 09 11:03:59 volumio volumio[29232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 11:03:59 volumio volumio[29232]: Error: send ENETUNREACH 255.255.255.255:3483 May 09 11:03:59 volumio volumio[29232]: at doSend (dgram.js:692:16) May 09 11:03:59 volumio volumio[29232]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 09 11:03:59 volumio volumio[29232]: at afterDns (dgram.js:638:5) May 09 11:03:59 volumio volumio[29232]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 09 11:03:59 volumio volumio[29232]: errno: -101, May 09 11:03:59 volumio volumio[29232]: code: 'ENETUNREACH', May 09 11:03:59 volumio volumio[29232]: syscall: 'send', May 09 11:03:59 volumio volumio[29232]: address: '255.255.255.255', May 09 11:03:59 volumio volumio[29232]: port: 3483 May 09 11:03:59 volumio volumio[29232]: } May 09 11:03:59 volumio volumio[29232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 11:04:00 volumio avahi-daemon[487]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.7. May 09 11:04:00 volumio avahi-daemon[487]: New relevant interface wlan0.IPv4 for mDNS. May 09 11:04:00 volumio dhcpcd[1053]: wlan0: adding route to 192.168.3.0/24 May 09 11:04:00 volumio avahi-daemon[487]: Registering new address record for 192.168.3.7 on wlan0.IPv4. May 09 11:04:00 volumio dhcpcd[1053]: wlan0: adding default route via 192.168.3.1 May 09 11:04:00 volumio sudo[29762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 11:03 May 09 11:04:00 volumio sudo[29762]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"