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