-- Logs begin at Fri 2025-01-17 15:48:47 JST, end at Fri 2025-01-17 18:08:44 JST. -- Jan 17 18:07:03 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:03+09:00" level=debug msg="fetched chunk 6/6, size: 503692" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:07:18 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:07:18] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jan 17 18:07:24 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:07:24] [connect] Successful connection Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CoreCommandRouter::volumioGetState Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CorePlayQueue::getTrack 2278 Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CoreCommandRouter::volumioGetState Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CorePlayQueue::getTrack 2278 Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CoreCommandRouter::volumioGetState Jan 17 18:07:25 volumio-pi3 volumio[11314]: info: CorePlayQueue::getTrack 2278 Jan 17 18:07:35 volumio-pi3 volumio[11314]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 18:07:36 volumio-pi3 volumio[11314]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Jan 17 18:07:36 volumio-pi3 volumio[11314]: code: 'auth/network-request-failed', Jan 17 18:07:36 volumio-pi3 volumio[11314]: a: null Jan 17 18:07:36 volumio-pi3 volumio[11314]: } Jan 17 18:07:36 volumio-pi3 volumio[11314]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 18:07:37 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:07:37] [connect] Successful connection Jan 17 18:07:54 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:07:54] [connect] Successful connection Jan 17 18:07:56 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:56+09:00" level=trace msg="emitting websocket event: not_playing" Jan 17 18:07:56 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:56+09:00" level=debug msg="loading track (paused: true, position: 39ms)" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:07:56 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:56+09:00" level=trace msg="cleared closed output device" Jan 17 18:07:56 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:56+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:07:56 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:56+09:00" level=trace msg="emitting websocket event: will_play" Jan 17 18:07:57 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:57+09:00" level=debug msg="selected format OGG_VORBIS_320 (1ed934a2aa098e55b07ae1c5735b0d124c257915)" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:07:57 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:57+09:00" level=debug msg="requested aes key for file 1ed934a2aa098e55b07ae1c5735b0d124c257915, gid: 5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:07:58 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=debug msg="fetched first chunk of 7, total size is 3649420 bytes" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=debug msg="fetched chunk 1/6, size: 524288" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=debug msg="fetched chunk 3/6, size: 524288" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=debug msg="fetched chunk 2/6, size: 524288" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:58+09:00" level=trace msg="seek to 39ms (diff: 39ms, samples: 1719, bytes: 0)" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:59+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:59+09:00" level=debug msg="created new output device" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:07:59+09:00" level=info msg="loaded track \"音(週末休み)\" (paused: true, position: 39ms, duration: 112943ms, prefetched: false)" uri="spotify:track:5aKtmQRWGgKYQdgW17FhqF" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=trace msg="emitting websocket event: metadata" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=trace msg="emitting websocket event: stopped" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=trace msg="emitting websocket event: stopped" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 17 18:08:00 volumio-pi3 go-librespot[28999]: time="2025-01-17T18:08:00+09:00" level=trace msg="emitting websocket event: paused" Jan 17 18:08:11 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:08:11] [connect] Successful connection Jan 17 18:08:17 volumio-pi3 systemd[1]: mpd.service: Start operation timed out. Terminating. Jan 17 18:08:19 volumio-pi3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:08:19 volumio-pi3 systemd[1]: mpd.service: Failed with result 'timeout'. Jan 17 18:08:19 volumio-pi3 systemd[1]: Failed to start Music Player Daemon. Jan 17 18:08:19 volumio-pi3 mpd_monitor.sh[604]: Job for mpd.service failed because a timeout was exceeded. Jan 17 18:08:19 volumio-pi3 mpd_monitor.sh[604]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jan 17 18:08:19 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jan 17 18:08:20 volumio-pi3 mpd_monitor.sh[604]: MPD restarted due to no mpc output. Jan 17 18:08:22 volumio-pi3 sudo[17413]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:08:22 volumio-pi3 sudo[17413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:08:22 volumio-pi3 sudo[17413]: pam_unix(sudo:session): session closed for user root Jan 17 18:08:24 volumio-pi3 nmbd[741]: [2025/01/17 18:08:24.088940, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 17 18:08:24 volumio-pi3 nmbd[741]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.111 for name WORKGROUP<1d>. Jan 17 18:08:24 volumio-pi3 nmbd[741]: This response was from IP 192.168.1.22, reporting an IP address of 192.168.1.22. Jan 17 18:08:24 volumio-pi3 nmbd[741]: [2025/01/17 18:08:24.141481, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 17 18:08:24 volumio-pi3 nmbd[741]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.111 for name WORKGROUP<1d>. Jan 17 18:08:24 volumio-pi3 nmbd[741]: This response was from IP 192.168.1.22, reporting an IP address of 192.168.1.22. Jan 17 18:08:24 volumio-pi3 nmbd[741]: [2025/01/17 18:08:24.142158, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 17 18:08:24 volumio-pi3 nmbd[741]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.111 for name WORKGROUP<1d>. Jan 17 18:08:24 volumio-pi3 nmbd[741]: This response was from IP 192.168.1.22, reporting an IP address of 192.168.1.22. Jan 17 18:08:28 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:08:28] [connect] Successful connection Jan 17 18:08:32 volumio-pi3 mpd_monitor.sh[604]: MPD error: Invalid argument Jan 17 18:08:34 volumio-pi3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 18:08:34 volumio-pi3 systemd[1]: mpd.service: Failed with result 'protocol'. Jan 17 18:08:34 volumio-pi3 systemd[1]: Failed to start Music Player Daemon. Jan 17 18:08:34 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jan 17 18:08:34 volumio-pi3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 17 18:08:34 volumio-pi3 systemd[1]: mpd.service: Succeeded. Jan 17 18:08:34 volumio-pi3 systemd[1]: Stopped Music Player Daemon. Jan 17 18:08:35 volumio-pi3 systemd[1]: Starting Music Player Daemon... Jan 17 18:08:36 volumio-pi3 sudo[17485]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 18:08:36 volumio-pi3 sudo[17485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 18:08:36 volumio-pi3 sudo[17485]: pam_unix(sudo:session): session closed for user root Jan 17 18:08:44 volumio-pi3 volumio-remote-updater[584]: [2025-01-17 18:08:44] [connect] Successful connection Jan 17 18:08:44 volumio-pi3 sudo[17491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 18:07 Jan 17 18:08:44 volumio-pi3 sudo[17491]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jan 2025 10:53:32 AM CET" VOLUMIO_VERSION="3.787" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a8dde41a0a74a6944b1f5d9c8dec1590"