-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Sat 2026-01-10 15:06:55 EST. -- Jan 10 15:05:06 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:06-05:00" level=debug msg="fetched chunk 8/10, size: 524288" uri="spotify:track:5uMVtPb97fMFuqqgE8zsgX" Jan 10 15:05:07 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:09 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:09 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:05:09 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:05:13 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:18 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:18-05:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:5uMVtPb97fMFuqqgE8zsgX" Jan 10 15:05:19 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:19 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:05:19 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:24 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:24-05:00" level=trace msg="sent dealer ping" Jan 10 15:05:24 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:24-05:00" level=trace msg="received dealer pong" Jan 10 15:05:26 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:29 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:29 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:05:29 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:05:29 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:05:30 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:30-05:00" level=debug msg="fetched chunk 10/10, size: 295063" uri="spotify:track:5uMVtPb97fMFuqqgE8zsgX" Jan 10 15:05:32 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:38 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:39 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:39 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:05:44 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:49 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:49 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:05:49 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:05:49 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:05:50 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:54 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:54-05:00" level=trace msg="sent dealer ping" Jan 10 15:05:54 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:54-05:00" level=trace msg="received dealer pong" Jan 10 15:05:55 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:55-05:00" level=trace msg="received accesspoint ping" Jan 10 15:05:55 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:05:55-05:00" level=trace msg="received accesspoint pong ack" Jan 10 15:05:56 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:05:59 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:05:59 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:06:02 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:08 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 166 Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: Prefetching next song Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: [1768075569887] ControllerSpotify::prefetch Jan 10 15:06:09 jt-musicsteamer volumio[1260]: info: Sending Spotify command with payload to local API: /player/add_to_queue Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="prefetching next track" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="selected format OGG_VORBIS_320 (2dc05ee587b5d4cb5e97dbf54fa2aa15ec98b042)" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="requested aes key for file 2dc05ee587b5d4cb5e97dbf54fa2aa15ec98b042, gid: 1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="fetched first chunk of 11, total size is 5399724 bytes" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=info msg="prefetched track \"Gonna Lose Myself\" (duration: 131124ms)" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:10 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:10-05:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=trace msg="emitting websocket event: not_playing" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:7aXV50jcJWXZYZe1zvOQK4","uri":"spotify:track:5uMVtPb97fMFuqqgE8zsgX","play_origin":"go-librespot"}} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: error: Failed to decode event: not_playing Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=trace msg="emitting websocket event: will_play" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=info msg="loaded track \"Gonna Lose Myself\" (paused: false, position: 0ms, duration: 131124ms, prefetched: true)" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7aXV50jcJWXZYZe1zvOQK4","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","play_origin":"go-librespot"}} Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=trace msg="scheduling prefetch in 101s" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=trace msg="emitting websocket event: metadata" Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","name":"Gonna Lose Myself","artist_names":["Tripssono","Ama Lur"],"album_name":"Gonna Lose Myself","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025880640ed02bc41e9b8a0267","position":0,"duration":131124,"release_date":"year:2023 month:4 day:21","track_number":1,"disc_number":1}} Jan 10 15:06:14 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 15:06:14 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:14-05:00" level=trace msg="emitting websocket event: playing" Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:7aXV50jcJWXZYZe1zvOQK4","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","resume":false,"play_origin":"go-librespot"}} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: {"status":"play","service":"spop","title":"Gonna Lose Myself","artist":"Tripssono, Ama Lur","album":"Gonna Lose Myself","albumart":"https://i.scdn.co/image/ab67616d00001e025880640ed02bc41e9b8a0267","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","trackType":"spotify","seek":0,"duration":131,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::servicePushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Gonna Lose Myself","artist":"Tripssono, Ama Lur","album":"Gonna Lose Myself","albumart":"https://i.scdn.co/image/ab67616d00001e025880640ed02bc41e9b8a0267","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","trackType":"spotify","seek":0,"duration":131,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: verbose: CURRENT POSITION 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::syncState stateService play Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: Received an update from plugin. extracting info from payload Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::pushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::pushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::pushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: {"status":"play","service":"spop","title":"Gonna Lose Myself","artist":"Tripssono, Ama Lur","album":"Gonna Lose Myself","albumart":"https://i.scdn.co/image/ab67616d00001e025880640ed02bc41e9b8a0267","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","trackType":"spotify","seek":0,"duration":131,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::servicePushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Gonna Lose Myself","artist":"Tripssono, Ama Lur","album":"Gonna Lose Myself","albumart":"https://i.scdn.co/image/ab67616d00001e025880640ed02bc41e9b8a0267","uri":"spotify:track:1bICyegSxUU2cKTWM3L7wv","trackType":"spotify","seek":0,"duration":131,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 15:06:14 jt-musicsteamer volumio[1260]: verbose: CURRENT POSITION 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::syncState stateService play Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: Received an update from plugin. extracting info from payload Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::pushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreStateMachine::pushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jan 10 15:06:14 jt-musicsteamer volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Jan 10 15:06:19 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:06:19 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:20 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:24 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:24-05:00" level=trace msg="sent dealer ping" Jan 10 15:06:24 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:24-05:00" level=trace msg="received dealer pong" Jan 10 15:06:26 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:28 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:28-05:00" level=debug msg="fetched chunk 4/10, size: 524288" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:29 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:06:29 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:29 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:06:29 jt-musicsteamer volumio[1260]: info: Listing playlists Jan 10 15:06:32 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:38 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:39 jt-musicsteamer volumio[1260]: info: CoreCommandRouter::volumioGetState Jan 10 15:06:39 jt-musicsteamer volumio[1260]: info: CorePlayQueue::getTrack 278 Jan 10 15:06:40 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:40-05:00" level=debug msg="fetched chunk 5/10, size: 524288" uri="spotify:track:1bICyegSxUU2cKTWM3L7wv" Jan 10 15:06:44 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: carrier lost Jan 10 15:06:45 jt-musicsteamer wpa_supplicant[1156]: wlan0: Associated with 9c:53:22:61:05:07 Jan 10 15:06:45 jt-musicsteamer wpa_supplicant[1156]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:53:22:61:05:07 completed [id=0 id_str=] Jan 10 15:06:45 jt-musicsteamer wpa_supplicant[1156]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 10 15:06:45 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:45 jt-musicsteamer avahi-daemon[797]: Withdrawing address record for 192.168.1.22 on wlan0. Jan 10 15:06:45 jt-musicsteamer avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.22. Jan 10 15:06:45 jt-musicsteamer avahi-daemon[797]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: deleting route to 192.168.0.0/22 Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: deleting default route via 192.168.1.1 Jan 10 15:06:45 jt-musicsteamer volumio[1260]: info: Discovery: A device disappeared from network Jan 10 15:06:45 jt-musicsteamer volumio[1260]: info: Discovery: Device jt-musicsteamer disappeared from network Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: carrier acquired Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: IAID 67:9d:84:a6 Jan 10 15:06:45 jt-musicsteamer dhcpcd[1171]: wlan0: rebinding lease of 192.168.1.22 Jan 10 15:06:46 jt-musicsteamer dhcpcd[1171]: wlan0: soliciting an IPv6 router Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: Deleting interface #8 wlan0, 192.168.1.22#123, interface stats: received=167, sent=169, dropped=0, active_time=2465 secs Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 198.137.202.32 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 45.79.214.107 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 45.55.58.103 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 69.89.207.99 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 207.58.172.126 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 72.14.182.49 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 216.250.115.174 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 96.231.54.40 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 24.229.44.105 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 23.186.168.127 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 23.186.168.128 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 66.118.229.14 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 162.244.81.139 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 204.2.134.173 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 144.202.0.197 local addr 192.168.1.22 -> Jan 10 15:06:46 jt-musicsteamer ntpd[1017]: 138.89.14.60 local addr 192.168.1.22 -> Jan 10 15:06:50 jt-musicsteamer wpa_supplicant[1156]: RRM: Ignoring radio measurement request: Not RRM network Jan 10 15:06:50 jt-musicsteamer dhcpcd[1171]: wlan0: probing address 192.168.1.22/22 Jan 10 15:06:52 jt-musicsteamer volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:06:52 jt-musicsteamer volumio[1260]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 10 15:06:52 jt-musicsteamer volumio[1260]: at doSend (dgram.js:692:16) Jan 10 15:06:52 jt-musicsteamer volumio[1260]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 10 15:06:52 jt-musicsteamer volumio[1260]: at afterDns (dgram.js:638:5) Jan 10 15:06:52 jt-musicsteamer volumio[1260]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 10 15:06:52 jt-musicsteamer volumio[1260]: errno: -101, Jan 10 15:06:52 jt-musicsteamer volumio[1260]: code: 'ENETUNREACH', Jan 10 15:06:52 jt-musicsteamer volumio[1260]: syscall: 'send', Jan 10 15:06:52 jt-musicsteamer volumio[1260]: address: '255.255.255.255', Jan 10 15:06:52 jt-musicsteamer volumio[1260]: port: 3483 Jan 10 15:06:52 jt-musicsteamer volumio[1260]: } Jan 10 15:06:52 jt-musicsteamer volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:06:52 jt-musicsteamer sudo[5542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 15:05 Jan 10 15:06:52 jt-musicsteamer sudo[5542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:52 jt-musicsteamer sudo[5542]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:52 jt-musicsteamer volumio-remote-updater[783]: [2026-01-10 15:06:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 10 15:06:52 jt-musicsteamer volumio-remote-updater[783]: [2026-01-10 15:06:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 10 15:06:52 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:52-05:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 10 15:06:52 jt-musicsteamer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 10 15:06:52 jt-musicsteamer systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 10 15:06:52 jt-musicsteamer systemd[1]: Started dynamicswap service. Jan 10 15:06:52 jt-musicsteamer systemd[1]: dynamicswap.service: Succeeded. Jan 10 15:06:52 jt-musicsteamer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 10 15:06:52 jt-musicsteamer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 10 15:06:52 jt-musicsteamer systemd[1]: Stopped Volumio Backend Module. Jan 10 15:06:52 jt-musicsteamer systemd[1]: Started Volumio Backend Module. Jan 10 15:06:52 jt-musicsteamer systemd[1]: Started dynamicswap service. Jan 10 15:06:52 jt-musicsteamer systemd[1]: dynamicswap.service: Succeeded. Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ------------------------------------------- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ----- Volumio3 ---- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ------------------------------------------- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ----- System startup ---- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ------------------------------------------- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: MYVOLUMIO Environment detected Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Plugin folders cleanup Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning into folder /volumio/app/plugins/ Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category audio_interface Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category miscellanea Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category music_service Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category plugins.json Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category system_controller Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category user_interface Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning into folder /data/plugins/ Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category music_service Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Scanning category user_interface Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Plugin folders cleanup completed Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ------------------------------------------- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ----- Core plugins startup ---- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: ------------------------------------------- Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugins from folder /volumio/app/plugins/ Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Adding plugin upnp to MyMusic Plugins Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugins from folder /data/plugins/ Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "system"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "appearance"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "network"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Refreshing Cached IP Addresses Jan 10 15:06:53 jt-musicsteamer sudo[5583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 15:06:53 jt-musicsteamer sudo[5583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:53 jt-musicsteamer sudo[5583]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:53 jt-musicsteamer sudo[5585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 15:06:53 jt-musicsteamer sudo[5585]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:53 jt-musicsteamer sudo[5585]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "services"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "alsa_controller"... Jan 10 15:06:53 jt-musicsteamer sudo[5598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 10 15:06:53 jt-musicsteamer sudo[5598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "wizard"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "networkfs"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Starting Udev Watcher for removable devices Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Ignoring mount for partition: boot Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Ignoring mount for partition: volumio Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Ignoring mount for partition: volumio_data Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "volumio_command_line_client"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "upnp"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: [1768075613536] Starting Upmpd Daemon Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "my_music"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "mpd"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "upnp_browser"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "alarm-clock"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "airplay_emulation"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Starting Shairport Sync Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "last_100"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "webradio"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "i2s_dacs"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "volumiodiscovery"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** For more information see Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 10 15:06:53 jt-musicsteamer volumio[5556]: *** WARNING *** For more information see Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** For more information see Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 10 15:06:53 jt-musicsteamer node[5556]: *** WARNING *** For more information see Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Discovery: Started advertising with name: JT-MusicSteamer Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "spop"... Jan 10 15:06:53 jt-musicsteamer volumio[5556]: info: Loading plugin "squeezelite_mc"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "now_playing"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "outputs"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "albumart"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Plugin example_plugin is not enabled Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "inputs"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "updater_comm"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Plugin mpdemulation is not enabled Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "rest_api"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "websocket"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Starting Socket.io Server version 2.3.0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "Systeminfo"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Forking 3 albumart workers Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Plugin peppymeterbasic is not enabled Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Plugin peppyspectrum is not enabled Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading plugin "touch_display"... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Applying required configuration parameters for plugin touch_display Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading i18n strings for locale en Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Updating browse sources language Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::initPlayerControls Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Express server listening on port 3000 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: [Metrics] WebUI: 1s 590.69ms Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::resetVolumioState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::getcurrentVolume Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Starting albumart workers Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Volumio Network Manager: Network status updated: 0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Starting albumart workers Jan 10 15:06:54 jt-musicsteamer volumio[5556]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: Starting albumart workers Jan 10 15:06:54 jt-musicsteamer volumio[5556]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Reloading queue from file Jan 10 15:06:54 jt-musicsteamer go-librespot[1649]: time="2026-01-10T15:06:54-05:00" level=trace msg="sent dealer ping" Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::setRepeat true single undefined Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::pushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrack 0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::setRandom true Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::pushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrack 0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Setting Device type: Raspberry PI Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Completed loading Core Plugins Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Preparing to generate the ALSA configuration file Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: VolumeController:: Volume=63 Mute =false Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::pushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrack 0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreStateMachine::updateTrackBlock Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrackBlock Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Asound.conf file unchanged, so no further update is needed Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Output device has changed, restarting MPD Jan 10 15:06:54 jt-musicsteamer sudo[5661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 15:06:54 jt-musicsteamer sudo[5661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer sudo[5661]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Output device has changed, restarting Shairport Sync Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 15:06:54 jt-musicsteamer sudo[5668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 15:06:54 jt-musicsteamer sudo[5668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: ___________ START PLUGINS ___________ Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: ControllerMpd::onStart: Initializing MPD Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Creating MPD Configuration file Jan 10 15:06:54 jt-musicsteamer systemd[1]: Stopping Music Player Daemon... Jan 10 15:06:54 jt-musicsteamer sudo[5682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 15:06:54 jt-musicsteamer sudo[5682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer systemd[1]: mpd.service: Succeeded. Jan 10 15:06:54 jt-musicsteamer systemd[1]: Stopped Music Player Daemon. Jan 10 15:06:54 jt-musicsteamer sudo[5682]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [1768075614819] CoreMusicLibrary::Adding element Media Servers Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 15:06:54 jt-musicsteamer sudo[5690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 15:06:54 jt-musicsteamer sudo[5690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer systemd[1]: Starting Music Player Daemon... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 15:06:54 jt-musicsteamer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 10 15:06:54 jt-musicsteamer systemd[1]: mpd.service: Succeeded. Jan 10 15:06:54 jt-musicsteamer systemd[1]: Stopped Music Player Daemon. Jan 10 15:06:54 jt-musicsteamer systemd[1]: Starting Music Player Daemon... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [1768075614856] CoreMusicLibrary::Adding element Last_100 Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [1768075614857] CoreMusicLibrary::Adding element Webradio Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Initializing BBC Radios Jan 10 15:06:54 jt-musicsteamer sudo[5696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 15:06:54 jt-musicsteamer sudo[5696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer sudo[5696]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Creating Spotify config file Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [squeezelite_mc] Starting proxy server... Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [now-playing] ConfigUpdater: config is up to date. Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Loading i18n strings for locale en Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: Volumio Calling Home Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [squeezelite_mc] Proxy server started on port 40665 Jan 10 15:06:54 jt-musicsteamer sudo[5715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 10 15:06:54 jt-musicsteamer sudo[5715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer sudo[5718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 10 15:06:54 jt-musicsteamer sudo[5718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer sudo[5720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 10 15:06:54 jt-musicsteamer sudo[5720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 15:06:54 jt-musicsteamer sudo[5715]: pam_unix(sudo:session): session closed for user root Jan 10 15:06:54 jt-musicsteamer systemd[1]: Reloading. Jan 10 15:06:54 jt-musicsteamer volumio[5556]: info: [now-playing] App is listening on port 4004. Jan 10 15:06:54 jt-musicsteamer volumio[5556]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 10 15:06:55 jt-musicsteamer volumio[5556]: Unhandled rejection Error: No sockets available, cannot start. Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: touch_display: Backlight interface detected. Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: MPD Permissions set Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: VolumeController:: Volume=63 Mute =false Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreStateMachine::pushState Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrack 0 Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: Spotify config file written Jan 10 15:06:55 jt-musicsteamer volumio[5556]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 10 15:06:55 jt-musicsteamer sudo[5728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: VolumeController:: Volume=63 Mute =false Jan 10 15:06:55 jt-musicsteamer sudo[5728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreStateMachine::pushState Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CorePlayQueue::getTrack 0 Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::volumioPushState Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: [squeezelite_mc] Server discovery started Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: [squeezelite_mc] Player finder started Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 15:06:55 jt-musicsteamer volumio[5556]: info: [squeezelite_mc] Executing aplay -D hw:0 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 10 15:06:55 jt-musicsteamer volumio[5556]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:06:55 jt-musicsteamer volumio[5556]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at doSend (dgram.js:692:16) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at afterDns (dgram.js:638:5) Jan 10 15:06:55 jt-musicsteamer volumio[5556]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 10 15:06:55 jt-musicsteamer volumio[5556]: errno: -101, Jan 10 15:06:55 jt-musicsteamer volumio[5556]: code: 'ENETUNREACH', Jan 10 15:06:55 jt-musicsteamer volumio[5556]: syscall: 'send', Jan 10 15:06:55 jt-musicsteamer volumio[5556]: address: '255.255.255.255', Jan 10 15:06:55 jt-musicsteamer volumio[5556]: port: 3483 Jan 10 15:06:55 jt-musicsteamer volumio[5556]: } Jan 10 15:06:55 jt-musicsteamer volumio[5556]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:06:55 jt-musicsteamer systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 10 15:06:55 jt-musicsteamer mpd[5702]: Jan 10 15:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 15:06:55 jt-musicsteamer dhcpcd[1171]: wlan0: leased 192.168.1.22 for 7200 seconds Jan 10 15:06:55 jt-musicsteamer avahi-daemon[797]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.22. Jan 10 15:06:55 jt-musicsteamer avahi-daemon[797]: New relevant interface wlan0.IPv4 for mDNS. Jan 10 15:06:55 jt-musicsteamer dhcpcd[1171]: wlan0: adding route to 192.168.0.0/22 Jan 10 15:06:55 jt-musicsteamer avahi-daemon[797]: Registering new address record for 192.168.1.22 on wlan0.IPv4. Jan 10 15:06:55 jt-musicsteamer dhcpcd[1171]: wlan0: adding default route via 192.168.1.1 Jan 10 15:06:55 jt-musicsteamer sudo[5777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 15:05 Jan 10 15:06:55 jt-musicsteamer sudo[5777]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 03:46:47 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e2f3a8aca3147ba51b5da8167dd6ff8"