-- Logs begin at Sun 2024-09-08 05:24:29 CEST, end at Sun 2024-09-08 11:33:14 CEST. -- Sep 08 11:32:02 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:12 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:12 volumio-slaapkamer volumio[952]: info: Listing playlists Sep 08 11:32:12 volumio-slaapkamer volumio[952]: info: Listing playlists Sep 08 11:32:14 volumio-slaapkamer go-librespot[18415]: time="2024-09-08T11:32:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Sep 08 11:32:14 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 11:32:14 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 11:32:14 volumio-slaapkamer volumio[952]: (node:952) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 08 11:32:14 volumio-slaapkamer volumio[952]: at connResetException (internal/errors.js:607:14) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: at Socket.emit (events.js:327:22) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: (node:952) 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: 25451) Sep 08 11:32:14 volumio-slaapkamer volumio[952]: info: Connection to go-librespot Websocket closed Sep 08 11:32:17 volumio-slaapkamer volumio[952]: info: Initializing connection to go-librespot Websocket Sep 08 11:32:17 volumio-slaapkamer volumio[952]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 11:32:17 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 11:32:17 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89951. Sep 08 11:32:17 volumio-slaapkamer systemd[1]: Stopped go-librespot Daemon. Sep 08 11:32:17 volumio-slaapkamer systemd[1]: Started go-librespot Daemon. Sep 08 11:32:17 volumio-slaapkamer go-librespot[18471]: Librespot-go daemon starting... Sep 08 11:32:17 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:17+02:00" level=info msg="generated new device id: 904b6c0a1ea50c61eb441288f95a6b529b5c1dc6" Sep 08 11:32:17 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:17+02:00" level=debug msg="stored credentials found for 1151341159" Sep 08 11:32:20 volumio-slaapkamer volumio[952]: info: Initializing connection to go-librespot Websocket Sep 08 11:32:20 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:20+02:00" level=debug msg="new websocket client" Sep 08 11:32:20 volumio-slaapkamer volumio[952]: info: Connection to go-librespot Websocket established Sep 08 11:32:22 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:22 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:22+02:00" level=debug msg="obtained new client token: AADVwo5iRoi69n8hQnhz0Nym0c02JGI2v3853RXrhdHnQh7sIbnT7P9YtYcaF3JPhBsU+Pv5hqeo7wHaYJeh1R6tu1Wk+FqS0FXiV+Upxq291fg9/St9Jf7YsT15/PfMc73pOETlIbb8sYyqh9SWgrtWgDZ1/8WIXrtPtzaL7T1AiCmVL1NBiOo6Y0a2AN0eWe3pGDd9GBplqQ23kHulEp8QoYlEXr2cIx5yj7KVGj6FC/NdMxey178+GAsyGCE=" Sep 08 11:32:23 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:23+02:00" level=debug msg="fetched new accesspoints: [ap2-gew4.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gew4.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gue1.spotify.com:443 ap2-gew1.spotify.com:80]" Sep 08 11:32:23 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:23+02:00" level=debug msg="completed keyexchange" Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: Getting Spotify volume Sep 08 11:32:23 volumio-slaapkamer volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:23 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:23 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:23+02:00" level=debug msg="completed challenge" Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: Clearing queue after UPNP request Sep 08 11:32:23 volumio-slaapkamer go-librespot[18471]: time="2024-09-08T11:32:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Sep 08 11:32:23 volumio-slaapkamer volumio[952]: (node:952) UnhandledPromiseRejectionWarning: Error: socket hang up Sep 08 11:32:23 volumio-slaapkamer volumio[952]: at connResetException (internal/errors.js:607:14) Sep 08 11:32:23 volumio-slaapkamer volumio[952]: at Socket.socketOnEnd (_http_client.js:493:23) Sep 08 11:32:23 volumio-slaapkamer volumio[952]: at Socket.emit (events.js:327:22) Sep 08 11:32:23 volumio-slaapkamer volumio[952]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 08 11:32:23 volumio-slaapkamer volumio[952]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 11:32:23 volumio-slaapkamer volumio[952]: (node:952) 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: 25452) Sep 08 11:32:23 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 11:32:23 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: Connection to go-librespot Websocket closed Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreStateMachine::ClearQueue Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreStateMachine::stop Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CorePlayQueue::clearPlayQueue Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CorePlayQueue::saveQueue Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:23 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:32:23 volumio-slaapkamer volumio[952]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 11:32:23 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:25 volumio-slaapkamer volumio[952]: info: Starting UPNP Playback Sep 08 11:32:25 volumio-slaapkamer volumio[952]: info: Preparing playback through UPNP Sep 08 11:32:25 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:25 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:25 volumio-slaapkamer volumio[952]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 2ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 2ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:26 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Initializing connection to go-librespot Websocket Sep 08 11:32:26 volumio-slaapkamer volumio[952]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 11:32:26 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 11:32:26 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89952. Sep 08 11:32:26 volumio-slaapkamer systemd[1]: Stopped go-librespot Daemon. Sep 08 11:32:26 volumio-slaapkamer systemd[1]: Started go-librespot Daemon. Sep 08 11:32:26 volumio-slaapkamer go-librespot[18487]: Librespot-go daemon starting... Sep 08 11:32:26 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:26+02:00" level=info msg="generated new device id: a25da23858755f27daf0b96fabbcd6a52155f68e" Sep 08 11:32:26 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:26+02:00" level=debug msg="stored credentials found for 1151341159" Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: Clearing queue after UPNP request Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:28 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:28 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 6 milliseconds Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 1 milliseconds Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/c56d38c8a370482f8bda7235eeb6418f.mp3?ts=1725787943","trackType":""} Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ------------------------------ 10ms Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/c56d38c8a370482f8bda7235eeb6418f.mp3?ts=1725787943","trackType":""} Sep 08 11:32:28 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: Received an update from plugin. extracting info from payload Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:28 volumio-slaapkamer volumio[952]: info: ------------------------------ 11ms Sep 08 11:32:28 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:28 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 2ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 1 milliseconds Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"c56d38c8a370482f8bda7235eeb6418f.mp3?ts=1725787943","artist":"Will Swenson/'Hair' Tribe - Donna","album":null,"uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/c56d38c8a370482f8bda7235eeb6418f.mp3?ts=1725787943","trackType":""} Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Received an update from plugin. extracting info from payload Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 6ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::ClearQueue Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::stop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::updateTrackBlock Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrackBlock Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::serviceStop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ControllerMpd::stop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::clearPlayQueue Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::saveQueue Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:32:29 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: sendMpdCommand stop took 18 milliseconds Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 2 milliseconds Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: 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} Sep 08 11:32:29 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService stop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: No code Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 9ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: error: updateQueue error: null Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 9ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Starting UPNP Playback Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Preparing playback through UPNP Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Initializing connection to go-librespot Websocket Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Connection to go-librespot Websocket established Sep 08 11:32:29 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:29+02:00" level=debug msg="new websocket client" Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 3ms Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:29 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:29 volumio-slaapkamer volumio[952]: info: ------------------------------ 1ms Sep 08 11:32:31 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:31+02:00" level=debug msg="obtained new client token: AAB4uWE9kxVLNnNs498K7qrFbmpwr8cZUqSUncEmreOSqUUbNMdfTvbtTj1hTBo8VFiXZEzGcCOk3l2n1JUUxDYgLIs5/UvNAF34/iRKCh+uQ9AwnVb5J+gU8F+GI33EA7PvBRTRXn0Az0kHz34Y+cZW+cos5v2lkTTJDnMXrmgivl8McjF/6pvbVPWKBriUKvwLVhrwjvKbL4wgzUaaF/CallBVkHVW7f1g7u8xQH0yEdl7oJHQHEGsHiOzqmY=" Sep 08 11:32:32 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:32+02:00" level=debug msg="fetched new accesspoints: [ap2-gew4.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gew4.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gew1.spotify.com:443 ap2-gae2.spotify.com:80]" Sep 08 11:32:32 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:32+02:00" level=debug msg="completed keyexchange" Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at /volumio/node_modules/express/lib/router/index.js:281:22 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Listing playlists Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Listing playlists Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:32 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 3 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:32 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787948","trackType":""} Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ------------------------------ 6ms Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 2 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787948","trackType":""} Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Received an update from plugin. extracting info from payload Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioPushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ------------------------------ 9ms Sep 08 11:32:32 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:32 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces system playlist update Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Ignoring MPD Status Update Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Sep 08 11:32:32 volumio-slaapkamer volumio[952]: ---------------------------- MPD announces state update: player Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::getState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ------------------------------ 2ms Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand status took 1 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ControllerMpd::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::servicePushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: In UPNP mode Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787948","artist":"Will Swenson/'Hair' Tribe - Donna","album":null,"uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787948","trackType":""} Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: CURRENT POSITION 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState stateService play Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Received an update from plugin. extracting info from payload Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreStateMachine::pushState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: ------------------------------ 4ms Sep 08 11:32:32 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:32+02:00" level=debug msg="completed challenge" Sep 08 11:32:32 volumio-slaapkamer go-librespot[18487]: time="2024-09-08T11:32:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Sep 08 11:32:32 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 08 11:32:32 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Connection to go-librespot Websocket closed Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: Getting Spotify volume Sep 08 11:32:32 volumio-slaapkamer volumio[952]: (node:952) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: (node:952) 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: 25453) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CoreCommandRouter::volumioGetState Sep 08 11:32:32 volumio-slaapkamer volumio[952]: info: CorePlayQueue::getTrack 0 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:32:32 volumio-slaapkamer volumio[952]: TypeError: Cannot read property 'name' of undefined Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at Socket.emit (events.js:315:20) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 08 11:32:32 volumio-slaapkamer volumio[952]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 08 11:32:32 volumio-slaapkamer volumio[952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:32:33 volumio-slaapkamer sudo[18524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 11:31 Sep 08 11:32:33 volumio-slaapkamer sudo[18524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:32:33 volumio-slaapkamer sudo[18524]: pam_unix(sudo:session): session closed for user root Sep 08 11:32:35 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 08 11:32:35 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89953. Sep 08 11:32:35 volumio-slaapkamer systemd[1]: Stopped go-librespot Daemon. Sep 08 11:32:35 volumio-slaapkamer systemd[1]: Started go-librespot Daemon. Sep 08 11:32:35 volumio-slaapkamer go-librespot[18531]: Librespot-go daemon starting... Sep 08 11:32:35 volumio-slaapkamer go-librespot[18531]: time="2024-09-08T11:32:35+02:00" level=info msg="generated new device id: 70ecfc2398318a344cbf899e6736369259990373" Sep 08 11:32:35 volumio-slaapkamer go-librespot[18531]: time="2024-09-08T11:32:35+02:00" level=debug msg="stored credentials found for 1151341159" Sep 08 11:32:36 volumio-slaapkamer volumiologrotate[624]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 08 11:32:36 volumio-slaapkamer volumiologrotate[624]: ls: cannot access 'SLAAPKAMER': No such file or directory Sep 08 11:32:45 volumio-slaapkamer volumio-remote-updater[10905]: [2024-09-08 11:32:45] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 08 11:32:49 volumio-slaapkamer go-librespot[18531]: time="2024-09-08T11:32:49+02:00" level=debug msg="obtained new client token: AAA5PdzpO9Yi2n/xODJYJFm5ScZr/EjEPhkYFPwDLEibiL5XaAryceHP5QwuHIn4l/ssIU4HvdnbDZMdWpWHeaEQpytcPiUT2i6/vcTCqIcx0RQeVdRI9iDB/i0YpbAf4Ro3KEPSmTgk2SIEY89jPuNBbZD54YePE8MzkNoAgn17Hh78IJYYwtXPS966jioTn/IfzzWLqeIijM04LtqFbFYVchT87foZ56EN4tK4Cim8dquYYCI0NWvQFofXsBE=" Sep 08 11:32:50 volumio-slaapkamer volumio-remote-updater[10905]: [2024-09-08 11:32:50] [connect] Successful connection Sep 08 11:33:01 volumio-slaapkamer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 08 11:33:01 volumio-slaapkamer systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: Started dynamicswap service. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: dynamicswap.service: Succeeded. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: Stopped Volumio Backend Module. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: Started Volumio Backend Module. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: Started dynamicswap service. Sep 08 11:33:01 volumio-slaapkamer systemd[1]: dynamicswap.service: Succeeded. Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: ------------------------------------------- Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: ----- Volumio3 ---- Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: ------------------------------------------- Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: ----- System startup ---- Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: ------------------------------------------- Sep 08 11:33:02 volumio-slaapkamer volumio-remote-updater[10905]: [2024-09-08 11:33:02] [connect] Successful connection Sep 08 11:33:02 volumio-slaapkamer volumio[18624]: info: MYVOLUMIO Environment detected Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Plugin folders cleanup Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning into folder /volumio/app/plugins/ Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category audio_interface Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category miscellanea Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category music_service Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category plugins.json Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category system_controller Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category user_interface Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning into folder /data/plugins/ Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Scanning category music_service Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Plugin folders cleanup completed Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: ------------------------------------------- Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: ----- Core plugins startup ---- Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: ------------------------------------------- Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugins from folder /volumio/app/plugins/ Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Adding plugin upnp to MyMusic Plugins Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugins from folder /data/plugins/ Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "system"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "appearance"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "network"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Refreshing Cached IP Addresses Sep 08 11:33:03 volumio-slaapkamer sudo[18652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:33:03 volumio-slaapkamer sudo[18652]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:03 volumio-slaapkamer sudo[18652]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:03 volumio-slaapkamer sudo[18654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:33:03 volumio-slaapkamer sudo[18654]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "services"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "alsa_controller"... Sep 08 11:33:03 volumio-slaapkamer sudo[18654]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:03 volumio-slaapkamer sudo[18658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 11:33:03 volumio-slaapkamer sudo[18658]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "wizard"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "networkfs"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Starting Udev Watcher for removable devices Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Ignoring mount for partition: boot Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Ignoring mount for partition: volumio Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Ignoring mount for partition: volumio_data Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "volumio_command_line_client"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "upnp"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: [1725787983795] Starting Upmpd Daemon Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "my_music"... Sep 08 11:33:03 volumio-slaapkamer volumio[18624]: info: Loading plugin "mpd"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Plugin upnp_browser is not enabled Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "alarm-clock"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "airplay_emulation"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Starting Shairport Sync Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "last_100"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "webradio"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "i2s_dacs"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: I2S DAC not set, start Auto-detection Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "volumiodiscovery"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** For more information see Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** For more information see Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: *** WARNING *** For more information see Sep 08 11:33:04 volumio-slaapkamer node[18624]: *** WARNING *** For more information see Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Discovery: Started advertising with name: Volumio Slaapkamer Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "jellyfin"... Sep 08 11:33:04 volumio-slaapkamer volumio[18624]: info: Loading plugin "spop"... Sep 08 11:33:05 volumio-slaapkamer volumio[18624]: info: Loading plugin "youtube2"... Sep 08 11:33:06 volumio-slaapkamer volumio[18624]: info: Loading plugin "ytcr"... Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Loading plugin "ytmusic"... Sep 08 11:33:07 volumio-slaapkamer sudo[18658]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Loading plugin "outputs"... Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Loading plugin "albumart"... Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Plugin example_plugin is not enabled Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Loading plugin "inputs"... Sep 08 11:33:07 volumio-slaapkamer volumio[18624]: info: Loading plugin "updater_comm"... Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Plugin mpdemulation is not enabled Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Loading plugin "rest_api"... Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Loading plugin "websocket"... Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Starting Socket.io Server version 2.3.0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Loading i18n strings for locale en Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Updating browse sources language Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::initPlayerControls Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Forking 3 albumart workers Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Express server listening on port 3000 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: [Metrics] WebUI: 5s 987.22ms Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::resetVolumioState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::getcurrentVolume Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioGetState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Reloading queue from file Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Clearing queue after UPNP request Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: VolumeController:: Volume=64 Mute =false Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::updateTrackBlock Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrackBlock Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::setRepeat null single undefined Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::setRandom null Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Setting Device type: Raspberry PI Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Listing playlists Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Listing playlists Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Completed loading Core Plugins Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Preparing to generate the ALSA configuration file Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::ClearQueue Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::stop Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::clearPlayQueue Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::saveQueue Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Starting albumart workers Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Starting albumart workers Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: VolumeController:: Volume=64 Mute =false Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Asound.conf file unchanged, so no further update is needed Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Output device has changed, restarting MPD Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: Starting albumart workers Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: Output device has changed, restarting Shairport Sync Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:08 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 11:33:09 volumio-slaapkamer sudo[18721]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer sudo[18724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 11:33:09 volumio-slaapkamer sudo[18724]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer sudo[18721]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Stopping Music Player Daemon... Sep 08 11:33:09 volumio-slaapkamer systemd[1]: mpd.service: Succeeded. Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Stopped Music Player Daemon. Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: ___________ START PLUGINS ___________ Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: ControllerMpd::onStart: Initializing MPD Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Creating MPD Configuration file Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Starting Music Player Daemon... Sep 08 11:33:09 volumio-slaapkamer sudo[18731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18731]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer sudo[18731]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:09 volumio-slaapkamer sudo[18734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 11:33:09 volumio-slaapkamer sudo[18734]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 08 11:33:09 volumio-slaapkamer systemd[1]: mpd.service: Succeeded. Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Stopped Music Player Daemon. Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Starting Music Player Daemon... Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 11:33:09 volumio-slaapkamer sudo[18740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer sudo[18740]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [1725787989338] CoreMusicLibrary::Adding element Last_100 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [1725787989341] CoreMusicLibrary::Adding element Webradio Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Initializing BBC Radios Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Discovery: Getting this device information Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioGetState Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [1725787989522] CoreMusicLibrary::Adding element Jellyfin Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source Jellyfin Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [jellyfin] Initialized plugin with device info: {"id":"2ab59a0c-38b4-44b2-b192-aa03903749fa","host":"http://192.168.178.214","name":"Volumio Slaapkamer","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":64,"mute":false,"artist":"","track":"","albumart":"http://192.168.178.214/albumart"}} Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Creating Spotify config file Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [1725787989628] CoreMusicLibrary::Adding element YouTube2 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source Jellyfin Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source YouTube2 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [1725787989676] CoreMusicLibrary::Adding element YouTube Music Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source Jellyfin Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source YouTube2 Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: Cannot find translation for source YouTube Music Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Volumio Calling Home Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: MPD Permissions set Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: MPD Permissions set Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Spotify config file written Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: [jellyfin-poller] Polled http://192.168.178.220:8096: online Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18785]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Stopping go-librespot Daemon... Sep 08 11:33:09 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 11:33:09 volumio-slaapkamer systemd[1]: go-librespot-daemon.service: Succeeded. Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Stopped go-librespot Daemon. Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:33:09 volumio-slaapkamer sudo[18785]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:09 volumio-slaapkamer systemd[1]: Started go-librespot Daemon. Sep 08 11:33:09 volumio-slaapkamer go-librespot[18791]: Librespot-go daemon starting... Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Starting Shairport Sync Sep 08 11:33:09 volumio-slaapkamer go-librespot[18791]: time="2024-09-08T11:33:09+02:00" level=info msg="generated new device id: 1b9013c7ebf96e0180c5b9309e5c6797db507ec7" Sep 08 11:33:09 volumio-slaapkamer go-librespot[18791]: time="2024-09-08T11:33:09+02:00" level=debug msg="stored credentials found for 1151341159" Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Starting Shairport Sync Sep 08 11:33:09 volumio-slaapkamer sudo[18803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 11:33:09 volumio-slaapkamer sudo[18803]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:09 volumio-slaapkamer volumio[18624]: info: Starting Shairport Sync Sep 08 11:33:10 volumio-slaapkamer sudo[18806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 11:33:10 volumio-slaapkamer sudo[18806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 11:33:10 volumio-slaapkamer systemd[1]: shairport-sync.service: Succeeded. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 11:33:10 volumio-slaapkamer sudo[18803]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:10 volumio-slaapkamer sudo[18809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 11:33:10 volumio-slaapkamer sudo[18809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:10 volumio-slaapkamer systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: info: Shairport-Sync Started Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: Error adding Membership: Error: addMembership EINVAL Sep 08 11:33:10 volumio-slaapkamer systemd[1]: shairport-sync.service: Succeeded. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 11:33:10 volumio-slaapkamer sudo[18806]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:10 volumio-slaapkamer systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 08 11:33:10 volumio-slaapkamer systemd[1]: shairport-sync.service: Succeeded. Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: info: Shairport-Sync Started Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 11:33:10 volumio-slaapkamer sudo[18809]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: info: Shairport-Sync Started Sep 08 11:33:10 volumio-slaapkamer mpd[18745]: Sep 08 11:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 11:33:10 volumio-slaapkamer systemd[1]: Started Music Player Daemon. Sep 08 11:33:10 volumio-slaapkamer sudo[18734]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:10 volumio-slaapkamer sudo[18724]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: error: MPD error: The expression evaluated to a falsy value: Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: assert.ok(self.idling) Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: error: The expression evaluated to a falsy value: Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: assert.ok(self.idling) Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: info: MPD running with PID18745 Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: ,establishing connection Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: error: updateQueue error: null Sep 08 11:33:10 volumio-slaapkamer volumio[18624]: error: updateQueue error: null Sep 08 11:33:11 volumio-slaapkamer volumio[18624]: info: Starting UPNP Playback Sep 08 11:33:11 volumio-slaapkamer volumio[18624]: info: Preparing playback through UPNP Sep 08 11:33:11 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioGetState Sep 08 11:33:11 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:11 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioGetState Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 6ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 4ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 4ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 2ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 2ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 2ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 1ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 4ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 2ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: ------------------------------ 2ms Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 11:33:12 volumio-slaapkamer volumio[18624]: info: go-librespot daemon successfully initialized Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 6 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 7 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 4 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 8 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 3 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 41ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 39ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 38ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 3 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 53ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 46ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Donna","artist":"Will Swenson/'Hair' Tribe","album":"Hair (The New Broadway Cast Recording)","uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioPushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 26ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 6ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 3 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 2 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces system playlist update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Ignoring MPD Status Update Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ---------------------------- MPD announces state update: player Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::getState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 3ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand status took 2 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","artist":"Will Swenson/'Hair' Tribe - Donna","album":null,"uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","artist":"Will Swenson/'Hair' Tribe - Donna","album":null,"uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 25ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 24ms Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ControllerMpd::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::servicePushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: In UPNP mode Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","artist":"Will Swenson/'Hair' Tribe - Donna","album":null,"uri":"http://192.168.178.22:8097/single/media_player.volumio_slaapkamer_upnp_av_2/49dee6516ba54bd89efc3f268e8ff520.mp3?ts=1725787987","trackType":""} Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: verbose: CURRENT POSITION 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState stateService play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Received an update from plugin. extracting info from payload Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreStateMachine::pushState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: ------------------------------ 20ms Sep 08 11:33:13 volumio-slaapkamer sudo[18832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 11:33:13 volumio-slaapkamer sudo[18832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:13 volumio-slaapkamer sudo[18832]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:13 volumio-slaapkamer sudo[18835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 11:33:13 volumio-slaapkamer sudo[18835]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:13 volumio-slaapkamer sudo[18835]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Discovery: adding 2ab59a0c-38b4-44b2-b192-aa03903749fa Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: Discovery: Found device Volumio Slaapkamer Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CoreCommandRouter::volumioGetState Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: info: CorePlayQueue::getTrack 0 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: TypeError: Cannot read property 'name' of undefined Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at Browser.emit (events.js:315:20) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Sep 08 11:33:13 volumio-slaapkamer volumio[18624]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:33:13 volumio-slaapkamer sudo[18838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 08 11:33:13 volumio-slaapkamer sudo[18838]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:33:13 volumio-slaapkamer sudo[18838]: pam_unix(sudo:session): session closed for user root Sep 08 11:33:14 volumio-slaapkamer sudo[18849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-08 11:32 Sep 08 11:33:14 volumio-slaapkamer sudo[18849]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"