-- Logs begin at Tue 2025-05-13 23:22:17 CST, end at Sun 2025-05-18 15:03:58 CST. -- May 18 15:02:10 volumio sshd[19527]: Accepted password for volumio from 192.168.1.26 port 54749 ssh2 May 18 15:02:10 volumio sshd[19527]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:02:10 volumio systemd-logind[755]: New session 45 of user volumio. May 18 15:02:10 volumio systemd[1]: Started Session 45 of user volumio. May 18 15:02:13 volumio sshd[19527]: pam_unix(sshd:session): session closed for user volumio May 18 15:02:13 volumio systemd[1]: session-45.scope: Succeeded. May 18 15:02:13 volumio systemd-logind[755]: Session 45 logged out. Waiting for processes to exit. May 18 15:02:13 volumio systemd-logind[755]: Removed session 45. May 18 15:02:27 volumio sshd[19571]: Accepted password for volumio from 192.168.1.26 port 54751 ssh2 May 18 15:02:27 volumio sshd[19571]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:02:27 volumio systemd-logind[755]: New session 46 of user volumio. May 18 15:02:27 volumio systemd[1]: Started Session 46 of user volumio. May 18 15:02:30 volumio sshd[19571]: pam_unix(sshd:session): session closed for user volumio May 18 15:02:30 volumio systemd[1]: session-46.scope: Succeeded. May 18 15:02:30 volumio systemd-logind[755]: Session 46 logged out. Waiting for processes to exit. May 18 15:02:30 volumio systemd-logind[755]: Removed session 46. May 18 15:02:37 volumio sshd[19637]: Accepted password for volumio from 192.168.1.26 port 54753 ssh2 May 18 15:02:37 volumio sshd[19637]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:02:37 volumio systemd-logind[755]: New session 47 of user volumio. May 18 15:02:37 volumio systemd[1]: Started Session 47 of user volumio. May 18 15:02:40 volumio sshd[19637]: pam_unix(sshd:session): session closed for user volumio May 18 15:02:40 volumio systemd[1]: session-47.scope: Succeeded. May 18 15:02:40 volumio systemd-logind[755]: Session 47 logged out. Waiting for processes to exit. May 18 15:02:40 volumio systemd-logind[755]: Removed session 47. May 18 15:02:45 volumio sshd[19662]: Accepted password for volumio from 192.168.1.26 port 54756 ssh2 May 18 15:02:45 volumio sshd[19662]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:02:45 volumio systemd-logind[755]: New session 48 of user volumio. May 18 15:02:45 volumio systemd[1]: Started Session 48 of user volumio. May 18 15:02:49 volumio sshd[19662]: pam_unix(sshd:session): session closed for user volumio May 18 15:02:49 volumio systemd[1]: session-48.scope: Succeeded. May 18 15:02:49 volumio systemd-logind[755]: Session 48 logged out. Waiting for processes to exit. May 18 15:02:49 volumio systemd-logind[755]: Removed session 48. May 18 15:02:55 volumio sshd[19693]: Accepted password for volumio from 192.168.1.26 port 54759 ssh2 May 18 15:02:55 volumio sshd[19693]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:02:55 volumio systemd-logind[755]: New session 49 of user volumio. May 18 15:02:55 volumio systemd[1]: Started Session 49 of user volumio. May 18 15:02:58 volumio sshd[19693]: pam_unix(sshd:session): session closed for user volumio May 18 15:02:58 volumio systemd[1]: session-49.scope: Succeeded. May 18 15:02:58 volumio systemd-logind[755]: Session 49 logged out. Waiting for processes to exit. May 18 15:02:58 volumio systemd-logind[755]: Removed session 49. May 18 15:03:03 volumio sshd[19704]: Accepted password for volumio from 192.168.1.26 port 54762 ssh2 May 18 15:03:03 volumio sshd[19704]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:03 volumio systemd-logind[755]: New session 50 of user volumio. May 18 15:03:03 volumio systemd[1]: Started Session 50 of user volumio. May 18 15:03:04 volumio volumio[13984]: info: CorePlayQueue::getTrack 26 May 18 15:03:04 volumio volumio[13984]: info: CorePlayQueue::getTrack 27 May 18 15:03:06 volumio sshd[19704]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:06 volumio systemd[1]: session-50.scope: Succeeded. May 18 15:03:06 volumio systemd-logind[755]: Session 50 logged out. Waiting for processes to exit. May 18 15:03:06 volumio systemd-logind[755]: Removed session 50. May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces system playlist update May 18 15:03:10 volumio volumio[13984]: info: Ignoring MPD Status Update May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces state update: player May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::getState May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::sendMpdCommand status May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces system playlist update May 18 15:03:10 volumio volumio[13984]: info: Ignoring MPD Status Update May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces state update: player May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::getState May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::sendMpdCommand status May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces system playlist update May 18 15:03:10 volumio volumio[13984]: info: Ignoring MPD Status Update May 18 15:03:10 volumio volumio[13984]: info: May 18 15:03:10 volumio volumio[13984]: ---------------------------- MPD announces state update: player May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::getState May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::sendMpdCommand status May 18 15:03:10 volumio volumio[13984]: error: updateQueue error: null May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 1ms May 18 15:03:10 volumio volumio[13984]: info: sendMpdCommand status took 1 milliseconds May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::parseState May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::pushState May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::servicePushState May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 26 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 26 May 18 15:03:10 volumio volumio[13984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 15:03:10 volumio volumio[13984]: verbose: CURRENT POSITION 26 May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState stateService stop May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState currentStatus play May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::stPlaybackTimer May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 9ms May 18 15:03:10 volumio volumio[13984]: error: updateQueue error: null May 18 15:03:10 volumio volumio[13984]: error: updateQueue error: null May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 9ms May 18 15:03:10 volumio volumio[13984]: info: sendMpdCommand status took 8 milliseconds May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 8ms May 18 15:03:10 volumio volumio[13984]: info: sendMpdCommand status took 8 milliseconds May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::parseState May 18 15:03:10 volumio volumio[13984]: verbose: ControllerMpd::parseState May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::pushState May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::servicePushState May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 15:03:10 volumio volumio[13984]: verbose: CURRENT POSITION 0 May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState stateService stop May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState currentStatus stop May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: No code May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: ControllerMpd::pushState May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::servicePushState May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 18 15:03:10 volumio volumio[13984]: verbose: CURRENT POSITION 0 May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState stateService stop May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::syncState currentStatus stop May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: No code May 18 15:03:10 volumio volumio[13984]: info: CoreStateMachine::pushState May 18 15:03:10 volumio volumio[13984]: info: CorePlayQueue::getTrack 0 May 18 15:03:10 volumio volumio[13984]: info: CoreCommandRouter::volumioPushState May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 19ms May 18 15:03:10 volumio volumio[13984]: info: ------------------------------ 20ms May 18 15:03:11 volumio sshd[19732]: Accepted password for volumio from 192.168.1.26 port 54763 ssh2 May 18 15:03:11 volumio sshd[19732]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:11 volumio systemd-logind[755]: New session 51 of user volumio. May 18 15:03:11 volumio systemd[1]: Started Session 51 of user volumio. May 18 15:03:15 volumio sshd[19732]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:15 volumio systemd[1]: session-51.scope: Succeeded. May 18 15:03:15 volumio systemd-logind[755]: Session 51 logged out. Waiting for processes to exit. May 18 15:03:15 volumio systemd-logind[755]: Removed session 51. May 18 15:03:23 volumio sshd[19760]: Accepted password for volumio from 192.168.1.26 port 54768 ssh2 May 18 15:03:23 volumio sshd[19760]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:23 volumio systemd-logind[755]: New session 52 of user volumio. May 18 15:03:23 volumio systemd[1]: Started Session 52 of user volumio. May 18 15:03:28 volumio sshd[19760]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:28 volumio systemd[1]: session-52.scope: Succeeded. May 18 15:03:28 volumio systemd-logind[755]: Session 52 logged out. Waiting for processes to exit. May 18 15:03:28 volumio systemd-logind[755]: Removed session 52. May 18 15:03:34 volumio sshd[19786]: Accepted password for volumio from 192.168.1.26 port 54772 ssh2 May 18 15:03:34 volumio sshd[19786]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:34 volumio systemd-logind[755]: New session 53 of user volumio. May 18 15:03:34 volumio systemd[1]: Started Session 53 of user volumio. May 18 15:03:37 volumio sshd[19786]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:37 volumio systemd[1]: session-53.scope: Succeeded. May 18 15:03:37 volumio systemd-logind[755]: Session 53 logged out. Waiting for processes to exit. May 18 15:03:37 volumio systemd-logind[755]: Removed session 53. May 18 15:03:43 volumio sshd[19852]: Accepted password for volumio from 192.168.1.26 port 54778 ssh2 May 18 15:03:43 volumio sshd[19852]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:43 volumio systemd-logind[755]: New session 54 of user volumio. May 18 15:03:43 volumio systemd[1]: Started Session 54 of user volumio. May 18 15:03:45 volumio sshd[19852]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:45 volumio systemd[1]: session-54.scope: Succeeded. May 18 15:03:45 volumio systemd-logind[755]: Session 54 logged out. Waiting for processes to exit. May 18 15:03:45 volumio systemd-logind[755]: Removed session 54. May 18 15:03:52 volumio sshd[19877]: Accepted password for volumio from 192.168.1.26 port 54782 ssh2 May 18 15:03:52 volumio sshd[19877]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 15:03:52 volumio systemd-logind[755]: New session 55 of user volumio. May 18 15:03:52 volumio systemd[1]: Started Session 55 of user volumio. May 18 15:03:55 volumio sshd[19877]: pam_unix(sshd:session): session closed for user volumio May 18 15:03:55 volumio systemd[1]: session-55.scope: Succeeded. May 18 15:03:55 volumio systemd-logind[755]: Session 55 logged out. Waiting for processes to exit. May 18 15:03:55 volumio systemd-logind[755]: Removed session 55. May 18 15:03:58 volumio volumio[13984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 15:03:58 volumio volumio[13984]: Error: connect ETIMEDOUT 199.59.148.97:443 May 18 15:03:58 volumio volumio[13984]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 18 15:03:58 volumio volumio[13984]: errno: -110, May 18 15:03:58 volumio volumio[13984]: code: 'ETIMEDOUT', May 18 15:03:58 volumio volumio[13984]: syscall: 'connect', May 18 15:03:58 volumio volumio[13984]: address: '199.59.148.97', May 18 15:03:58 volumio volumio[13984]: port: 443 May 18 15:03:58 volumio volumio[13984]: } May 18 15:03:58 volumio volumio[13984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 15:03:58 volumio sudo[19915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 15:02 May 18 15:03:58 volumio sudo[19915]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"