-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-12-05 14:35:35 CET. -- Dec 05 14:34:00 volumioaz volumio[899]: info: [jellyfin-poller] Polled http://192.168.178.62:30013: offline Dec 05 14:34:02 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:02 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:02 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:02 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:02 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:02 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:02 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:02 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:02 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:02 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:02 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:02 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:02 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:05 volumioaz go-librespot[1227]: time="2025-12-05T14:34:05+01:00" level=trace msg="sent dealer ping" Dec 05 14:34:05 volumioaz go-librespot[1227]: time="2025-12-05T14:34:05+01:00" level=trace msg="received dealer pong" Dec 05 14:34:09 volumioaz nmbd[677]: [2025/12/05 14:34:09.309670, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 05 14:34:09 volumioaz nmbd[677]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.22 for name WORKGROUP<1d>. Dec 05 14:34:09 volumioaz nmbd[677]: This response was from IP 192.168.178.24, reporting an IP address of 192.168.178.24. Dec 05 14:34:12 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:12 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:12 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:12 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:12 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:12 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:12 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:12 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:12 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:12 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:12 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:12 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:12 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:12 volumioaz volumio[899]: info: Listing playlists Dec 05 14:34:12 volumioaz volumio[899]: info: Listing playlists Dec 05 14:34:22 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:22 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:22 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:22 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:22 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:22 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:22 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:22 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:22 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:22 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:22 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:22 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:22 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:23 volumioaz volumio[899]: info: Dec 05 14:34:23 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:23 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:23 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:23 volumioaz volumio[899]: info: sendMpdCommand status took 2 milliseconds Dec 05 14:34:23 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:23 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:23 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 05 14:34:23 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:23 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:23 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:23 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:23 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36487,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"bdae84ba2345461f957bd8382cae309a.mp3","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/4qAwAn2S/media_player.volumio_az_upnp_av/bdae84ba2345461f957bd8382cae309a.mp3","trackType":"mp3"} Dec 05 14:34:23 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:23 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService pause Dec 05 14:34:23 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:34:23 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:23 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:23 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:23 volumioaz volumio[899]: info: ------------------------------ 16ms Dec 05 14:34:32 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:32 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:32 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:32 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:32 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:32 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:32 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:32 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:32 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:32 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:32 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:32 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:32 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:32 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:32 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:32 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:32 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:32 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:32 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:33 volumioaz volumio[899]: info: Listing playlists Dec 05 14:34:33 volumioaz volumio[899]: info: Listing playlists Dec 05 14:34:33 volumioaz volumio[899]: info: [jellyfin-poller] Polled http://192.168.178.62:30013: offline Dec 05 14:34:35 volumioaz go-librespot[1227]: time="2025-12-05T14:34:35+01:00" level=trace msg="sent dealer ping" Dec 05 14:34:35 volumioaz go-librespot[1227]: time="2025-12-05T14:34:35+01:00" level=trace msg="received dealer pong" Dec 05 14:34:36 volumioaz volumio[899]: info: Dec 05 14:34:36 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:36 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:36 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:36 volumioaz volumio[899]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:34:36 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:36 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:36 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:34:36 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:36 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:36 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:36 volumioaz volumio[899]: info: ------------------------------ 15ms Dec 05 14:34:36 volumioaz volumio[899]: info: Clearing queue after UPNP request Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::ClearQueue Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::stop Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::stPlaybackTimer Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::updateTrackBlock Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrackBlock Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushState Dec 05 14:34:36 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output update for this device Dec 05 14:34:36 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:36 volumioaz volumio[899]: info: CoreStateMachine::serviceStop Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:36 volumioaz volumio[899]: info: ControllerMpd::stop Dec 05 14:34:36 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::clearPlayQueue Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::saveQueue Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushState Dec 05 14:34:36 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output update for this device Dec 05 14:34:36 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:36 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:36 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushQueue Dec 05 14:34:36 volumioaz volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 05 14:34:36 volumioaz volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: sendMpdCommand stop took 123 milliseconds Dec 05 14:34:37 volumioaz volumio[899]: error: updateQueue error: null Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 7ms Dec 05 14:34:37 volumioaz volumio[899]: info: Starting UPNP Playback Dec 05 14:34:37 volumioaz volumio[899]: info: Preparing playback through UPNP Dec 05 14:34:37 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:37 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:37 volumioaz volumio[899]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 5ms Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 4ms Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 3ms Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 4ms Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 3ms Dec 05 14:34:37 volumioaz volumio[899]: info: Dec 05 14:34:37 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:37 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:37 volumioaz volumio[899]: info: ------------------------------ 4ms Dec 05 14:34:38 volumioaz volumio[899]: info: Dec 05 14:34:38 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:38 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:38 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:38 volumioaz volumio[899]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:34:38 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:38 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:38 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:34:38 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:38 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:38 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:38 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:38 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.178.24:30275/flow/e9mLyeni/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/768f2d78b2c54975ad7f13d61050a452.flac","trackType":"flac"} Dec 05 14:34:38 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:38 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService pause Dec 05 14:34:38 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:34:38 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:38 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:38 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:38 volumioaz volumio[899]: info: ------------------------------ 18ms Dec 05 14:34:39 volumioaz volumio[899]: info: Dec 05 14:34:39 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:39 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:39 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:39 volumioaz volumio[899]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:34:39 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:39 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:39 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:34:39 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:39 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:39 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:39 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:39 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.178.24:30275/flow/e9mLyeni/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/768f2d78b2c54975ad7f13d61050a452.flac","trackType":"flac"} Dec 05 14:34:39 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:39 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:34:39 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:34:39 volumioaz volumio[899]: info: ------------------------------ 14ms Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:42 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:42 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:42 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:42 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:42 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:42 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:42 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:42 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:42 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:42 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:42 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:42 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:42 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:42 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:42 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:42 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:42 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:42 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:42 volumioaz volumio[899]: info: Dec 05 14:34:42 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:42 volumioaz volumio[899]: info: Dec 05 14:34:42 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand status took 6 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:42 volumioaz volumio[899]: info: Dec 05 14:34:42 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:34:42 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:34:42 volumioaz volumio[899]: info: Dec 05 14:34:42 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand status took 8 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:42 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:42 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"768f2d78b2c54975ad7f13d61050a452.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/e9mLyeni/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/768f2d78b2c54975ad7f13d61050a452.flac","trackType":"flac"} Dec 05 14:34:42 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:34:42 volumioaz volumio[899]: info: Received an update from plugin. extracting info from payload Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:42 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:42 volumioaz volumio[899]: info: ------------------------------ 36ms Dec 05 14:34:42 volumioaz volumio[899]: info: ------------------------------ 23ms Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand status took 19 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:42 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:42 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"266 Kbps","isStreaming":false,"title":"768f2d78b2c54975ad7f13d61050a452.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/e9mLyeni/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/768f2d78b2c54975ad7f13d61050a452.flac","trackType":"flac"} Dec 05 14:34:42 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:34:42 volumioaz volumio[899]: info: Received an update from plugin. extracting info from payload Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:42 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:42 volumioaz volumio[899]: info: ------------------------------ 47ms Dec 05 14:34:42 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 05 14:34:42 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:34:42 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:34:42 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:34:42 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1219,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"252 Kbps","isStreaming":false,"title":"768f2d78b2c54975ad7f13d61050a452.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/e9mLyeni/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/768f2d78b2c54975ad7f13d61050a452.flac","trackType":"flac"} Dec 05 14:34:42 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:34:42 volumioaz volumio[899]: info: Received an update from plugin. extracting info from payload Dec 05 14:34:42 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:34:42 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:34:42 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:42 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:42 volumioaz volumio[899]: info: ------------------------------ 51ms Dec 05 14:34:52 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:34:52 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:34:52 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:34:52 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:34:52 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:34:52 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:34:52 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:52 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:34:52 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:34:52 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:52 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:34:52 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:52 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:52 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:34:52 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:34:52 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:34:52 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:34:52 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:34:52 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:34:52 volumioaz volumio[899]: info: Listing playlists Dec 05 14:34:52 volumioaz volumio[899]: info: Listing playlists Dec 05 14:35:02 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:02 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:02 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:02 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:35:02 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:35:02 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:35:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:35:02 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:35:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:02 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:35:02 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:02 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:35:02 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:02 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:35:02 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:35:02 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:02 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:05 volumioaz go-librespot[1227]: time="2025-12-05T14:35:05+01:00" level=trace msg="sent dealer ping" Dec 05 14:35:05 volumioaz go-librespot[1227]: time="2025-12-05T14:35:05+01:00" level=trace msg="received dealer pong" Dec 05 14:35:06 volumioaz volumio[899]: info: [jellyfin-poller] Polled http://192.168.178.62:30013: offline Dec 05 14:35:10 volumioaz volumio[899]: info: Dec 05 14:35:10 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:35:10 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:35:10 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:35:10 volumioaz volumio[899]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:35:10 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:35:10 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:35:10 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 05 14:35:10 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:35:10 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:35:10 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:35:10 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:35:10 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:10 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:10 volumioaz volumio[899]: info: ------------------------------ 15ms Dec 05 14:35:10 volumioaz volumio[899]: info: Clearing queue after UPNP request Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::ClearQueue Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::stop Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::stPlaybackTimer Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::updateTrackBlock Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrackBlock Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushState Dec 05 14:35:11 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output update for this device Dec 05 14:35:11 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::serviceStop Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:11 volumioaz volumio[899]: info: ControllerMpd::stop Dec 05 14:35:11 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand stop Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::clearPlayQueue Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::saveQueue Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushState Dec 05 14:35:11 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output update for this device Dec 05 14:35:11 volumioaz volumio[899]: info: MRS: Pushing multiroomSync output Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioPushQueue Dec 05 14:35:11 volumioaz volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 05 14:35:11 volumioaz volumio[899]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 05 14:35:11 volumioaz volumio[899]: info: sendMpdCommand stop took 96 milliseconds Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: error: updateQueue error: null Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 5ms Dec 05 14:35:11 volumioaz volumio[899]: info: Starting UPNP Playback Dec 05 14:35:11 volumioaz volumio[899]: info: Preparing playback through UPNP Dec 05 14:35:11 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:11 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:11 volumioaz volumio[899]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 5ms Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 4ms Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 3ms Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 3ms Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 3ms Dec 05 14:35:11 volumioaz volumio[899]: info: Dec 05 14:35:11 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:11 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:11 volumioaz volumio[899]: info: ------------------------------ 4ms Dec 05 14:35:12 volumioaz volumio[899]: info: Dec 05 14:35:12 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:35:12 volumioaz volumio[899]: info: Dec 05 14:35:12 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand status took 5 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand status took 5 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:35:12 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:35:12 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Music Assistant","artist":null,"album":null,"uri":"http://192.168.178.24:30275/flow/cNJ64kVn/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/52dfadfb705e4848b0291717eef8d4d1.flac","trackType":"flac"} Dec 05 14:35:12 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus stop Dec 05 14:35:12 volumioaz volumio[899]: info: ------------------------------ 24ms Dec 05 14:35:12 volumioaz volumio[899]: info: Dec 05 14:35:12 volumioaz volumio[899]: ---------------------------- MPD announces system playlist update Dec 05 14:35:12 volumioaz volumio[899]: info: Ignoring MPD Status Update Dec 05 14:35:12 volumioaz volumio[899]: info: Dec 05 14:35:12 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:35:12 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:35:12 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52dfadfb705e4848b0291717eef8d4d1.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/cNJ64kVn/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/52dfadfb705e4848b0291717eef8d4d1.flac","trackType":"flac"} Dec 05 14:35:12 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:35:12 volumioaz volumio[899]: info: Received an update from plugin. extracting info from payload Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:35:12 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:12 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:12 volumioaz volumio[899]: info: ------------------------------ 38ms Dec 05 14:35:12 volumioaz volumio[899]: info: ------------------------------ 21ms Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand status took 14 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:35:12 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:35:12 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:35:12 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:35:12 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:35:12 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1219,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"711 Kbps","isStreaming":false,"title":"52dfadfb705e4848b0291717eef8d4d1.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/cNJ64kVn/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/52dfadfb705e4848b0291717eef8d4d1.flac","trackType":"flac"} Dec 05 14:35:12 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService play Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:35:12 volumioaz volumio[899]: info: Received an update from plugin. extracting info from payload Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 05 14:35:12 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:35:12 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:12 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:12 volumioaz volumio[899]: info: ------------------------------ 35ms Dec 05 14:35:12 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:12 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:12 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:12 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:35:12 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:35:12 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:35:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:35:12 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:35:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:12 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:35:12 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:12 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:35:12 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:12 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:35:12 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:35:12 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:12 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:12 volumioaz volumio[899]: info: Listing playlists Dec 05 14:35:12 volumioaz volumio[899]: info: Listing playlists Dec 05 14:35:15 volumioaz volumio[899]: info: Dec 05 14:35:15 volumioaz volumio[899]: ---------------------------- MPD announces state update: player Dec 05 14:35:15 volumioaz volumio[899]: info: ControllerMpd::getState Dec 05 14:35:15 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand status Dec 05 14:35:15 volumioaz volumio[899]: info: sendMpdCommand status took 3 milliseconds Dec 05 14:35:15 volumioaz volumio[899]: verbose: ControllerMpd::parseState Dec 05 14:35:15 volumioaz volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 05 14:35:15 volumioaz volumio[899]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 05 14:35:15 volumioaz volumio[899]: verbose: ControllerMpd::parseTrackInfo Dec 05 14:35:15 volumioaz volumio[899]: info: ControllerMpd::pushState Dec 05 14:35:15 volumioaz volumio[899]: info: CoreCommandRouter::servicePushState Dec 05 14:35:15 volumioaz volumio[899]: verbose: In UPNP mode Dec 05 14:35:15 volumioaz volumio[899]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4603,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"714 Kbps","isStreaming":false,"title":"52dfadfb705e4848b0291717eef8d4d1.flac","artist":"Music Assistant","album":null,"uri":"http://192.168.178.24:30275/flow/cNJ64kVn/uuid:ed8aec51-16d7-ff65-dfe4-b827eba1b124/52dfadfb705e4848b0291717eef8d4d1.flac","trackType":"flac"} Dec 05 14:35:15 volumioaz volumio[899]: verbose: CURRENT POSITION 0 Dec 05 14:35:15 volumioaz volumio[899]: info: CoreStateMachine::syncState stateService pause Dec 05 14:35:15 volumioaz volumio[899]: info: CoreStateMachine::syncState currentStatus play Dec 05 14:35:15 volumioaz volumio[899]: info: CoreStateMachine::pushState Dec 05 14:35:15 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:15 volumioaz volumio[899]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:15 volumioaz volumio[899]: info: ------------------------------ 16ms Dec 05 14:35:22 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:22 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:22 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:22 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:35:22 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:35:22 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:35:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:35:22 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:35:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:22 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:35:22 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:22 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:35:22 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:22 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:35:22 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:35:22 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:22 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:27 volumioaz dhcpcd[797]: wlan0: carrier lost Dec 05 14:35:27 volumioaz wpa_supplicant[783]: wlan0: Associated with 48:5d:35:c4:c4:5d Dec 05 14:35:27 volumioaz wpa_supplicant[783]: wlan0: CTRL-EVENT-CONNECTED - Connection to 48:5d:35:c4:c4:5d completed [id=0 id_str=] Dec 05 14:35:27 volumioaz wpa_supplicant[783]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 05 14:35:27 volumioaz avahi-daemon[490]: Withdrawing address record for 192.168.178.22 on wlan0. Dec 05 14:35:27 volumioaz avahi-daemon[490]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.22. Dec 05 14:35:27 volumioaz dhcpcd[797]: wlan0: deleting route to 192.168.178.0/24 Dec 05 14:35:27 volumioaz dhcpcd[797]: wlan0: deleting default route via 192.168.178.1 Dec 05 14:35:27 volumioaz avahi-daemon[490]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 05 14:35:27 volumioaz volumio[899]: info: Discovery: A device disappeared from network Dec 05 14:35:27 volumioaz volumio[899]: info: Discovery: A device disappeared from network Dec 05 14:35:27 volumioaz ntpd[888]: ntpd exiting on signal 15 (Terminated) Dec 05 14:35:27 volumioaz systemd[1]: Stopping Network Time Service... Dec 05 14:35:27 volumioaz ntpd[888]: 192.168.178.1 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 139.162.156.95 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 178.63.52.50 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 217.160.19.219 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 185.13.148.71 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 81.169.217.236 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 217.144.138.234 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 213.239.234.28 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 81.169.233.252 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 88.198.7.62 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 176.9.157.155 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 85.215.189.120 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz ntpd[888]: 162.159.200.123 local addr 192.168.178.22 -> Dec 05 14:35:27 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 14:35:27 volumioaz systemd[1]: ntp.service: Succeeded. Dec 05 14:35:27 volumioaz systemd[1]: Stopped Network Time Service. Dec 05 14:35:27 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 14:35:27 volumioaz systemd[1]: Starting Network Time Service... Dec 05 14:35:27 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 14:35:27 volumioaz ntpd[2741]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 05 14:35:27 volumioaz ntpd[2741]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 05 14:35:27 volumioaz systemd[1]: Started Network Time Service. Dec 05 14:35:27 volumioaz dhcpcd[797]: wlan0: carrier acquired Dec 05 14:35:27 volumioaz ntpd[2747]: proto: precision = 1.406 usec (-19) Dec 05 14:35:27 volumioaz ntpd[2747]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 05 14:35:27 volumioaz ntpd[2747]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 05 14:35:27 volumioaz ntpd[2747]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 343 days ago Dec 05 14:35:27 volumioaz ntpd[2747]: Listen and drop on 0 v6wildcard [::]:123 Dec 05 14:35:27 volumioaz ntpd[2747]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 05 14:35:27 volumioaz ntpd[2747]: Listen normally on 2 lo 127.0.0.1:123 Dec 05 14:35:27 volumioaz ntpd[2747]: Listening on routing socket on fd #19 for interface updates Dec 05 14:35:27 volumioaz ntpd[2747]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 05 14:35:27 volumioaz ntpd[2747]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 05 14:35:27 volumioaz dhcpcd[797]: wlan0: IAID eb:a1:b1:24 Dec 05 14:35:28 volumioaz dhcpcd[797]: wlan0: soliciting a DHCP lease Dec 05 14:35:28 volumioaz dhcpcd[797]: wlan0: offered 192.168.178.22 from 192.168.178.1 Dec 05 14:35:28 volumioaz dhcpcd[797]: wlan0: probing address 192.168.178.22/24 Dec 05 14:35:28 volumioaz dhcpcd[797]: wlan0: soliciting an IPv6 router Dec 05 14:35:28 volumioaz ntpd[2747]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 05 14:35:29 volumioaz ntpd[2747]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 05 14:35:30 volumioaz ntpd[2747]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 05 14:35:31 volumioaz ntpd[2747]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 05 14:35:33 volumioaz dhcpcd[797]: wlan0: leased 192.168.178.22 for 864000 seconds Dec 05 14:35:33 volumioaz avahi-daemon[490]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.22. Dec 05 14:35:33 volumioaz avahi-daemon[490]: New relevant interface wlan0.IPv4 for mDNS. Dec 05 14:35:33 volumioaz dhcpcd[797]: wlan0: adding route to 192.168.178.0/24 Dec 05 14:35:33 volumioaz dhcpcd[797]: wlan0: adding default route via 192.168.178.1 Dec 05 14:35:33 volumioaz avahi-daemon[490]: Registering new address record for 192.168.178.22 on wlan0.IPv4. Dec 05 14:35:33 volumioaz ntpd[2747]: ntpd exiting on signal 15 (Terminated) Dec 05 14:35:33 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:33 volumioaz systemd[1]: Stopping Network Time Service... Dec 05 14:35:33 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:33 volumioaz systemd[1]: ntp.service: Succeeded. Dec 05 14:35:33 volumioaz systemd[1]: Stopped Network Time Service. Dec 05 14:35:33 volumioaz volumio[899]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:33 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:35:33 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:35:33 volumioaz volumio[899]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Dec 05 14:35:33 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:33 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 05 14:35:33 volumioaz volumio[899]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 05 14:35:33 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:33 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 05 14:35:33 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:33 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:33 volumioaz volumio[899]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Dec 05 14:35:33 volumioaz volumio[899]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 05 14:35:33 volumioaz volumio[899]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 05 14:35:33 volumioaz volumio[899]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 05 14:35:33 volumioaz volumio[899]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 05 14:35:33 volumioaz volumio[899]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 05 14:35:33 volumioaz volumio[899]: info: MRS: Found cast device: TPM171E-a458329889d7a8f339da2845752c821f Dec 05 14:35:33 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 14:35:33 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 05 14:35:33 volumioaz systemd[1]: Starting Network Time Service... Dec 05 14:35:33 volumioaz volumio[899]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 05 14:35:33 volumioaz ntpd[2787]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 05 14:35:33 volumioaz ntpd[2787]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Dec 05 14:35:33 volumioaz systemd[1]: Started Network Time Service. Dec 05 14:35:33 volumioaz ntpd[2793]: proto: precision = 1.458 usec (-19) Dec 05 14:35:33 volumioaz ntpd[2793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 05 14:35:33 volumioaz ntpd[2793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 05 14:35:33 volumioaz ntpd[2793]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 343 days ago Dec 05 14:35:33 volumioaz ntpd[2793]: Listen and drop on 0 v6wildcard [::]:123 Dec 05 14:35:33 volumioaz ntpd[2793]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 05 14:35:33 volumioaz ntpd[2793]: Listen normally on 2 lo 127.0.0.1:123 Dec 05 14:35:33 volumioaz ntpd[2793]: Listen normally on 3 wlan0 192.168.178.22:123 Dec 05 14:35:33 volumioaz ntpd[2793]: Listening on routing socket on fd #20 for interface updates Dec 05 14:35:33 volumioaz ntpd[2793]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 05 14:35:33 volumioaz ntpd[2793]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Dec 05 14:35:34 volumioaz volumio[899]: info: Listing playlists Dec 05 14:35:34 volumioaz volumio[899]: info: Listing playlists Dec 05 14:35:34 volumioaz volumio[899]: info: Discovery: adding aec5508c-bd28-422d-a578-52c33364b20c Dec 05 14:35:34 volumioaz volumio[899]: info: Discovery: Found device Volumio_AZ Dec 05 14:35:34 volumioaz volumio[899]: info: CoreCommandRouter::volumioGetState Dec 05 14:35:34 volumioaz volumio[899]: info: CorePlayQueue::getTrack 0 Dec 05 14:35:34 volumioaz volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:35:34 volumioaz volumio[899]: TypeError: Cannot read property 'name' of undefined Dec 05 14:35:34 volumioaz volumio[899]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 05 14:35:34 volumioaz volumio[899]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 05 14:35:34 volumioaz volumio[899]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:272:40) Dec 05 14:35:34 volumioaz volumio[899]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Dec 05 14:35:34 volumioaz volumio[899]: at Browser.emit (events.js:315:20) Dec 05 14:35:34 volumioaz volumio[899]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Dec 05 14:35:34 volumioaz volumio[899]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Dec 05 14:35:34 volumioaz volumio[899]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Dec 05 14:35:34 volumioaz volumio[899]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Dec 05 14:35:34 volumioaz volumio[899]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Dec 05 14:35:34 volumioaz volumio[899]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Dec 05 14:35:34 volumioaz volumio[899]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Dec 05 14:35:34 volumioaz volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 14:35:34 volumioaz ntpd[2793]: Soliciting pool server 131.188.3.222 Dec 05 14:35:34 volumioaz go-librespot[1227]: time="2025-12-05T14:35:34+01:00" level=trace msg="received accesspoint ping" Dec 05 14:35:35 volumioaz go-librespot[1227]: time="2025-12-05T14:35:35+01:00" level=trace msg="received accesspoint pong ack" Dec 05 14:35:35 volumioaz go-librespot[1227]: time="2025-12-05T14:35:35+01:00" level=trace msg="sent dealer ping" Dec 05 14:35:35 volumioaz go-librespot[1227]: time="2025-12-05T14:35:35+01:00" level=trace msg="received dealer pong" Dec 05 14:35:35 volumioaz sudo[2804]: volumio : unable to resolve host volumioaz Dec 05 14:35:35 volumioaz sudo[2804]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 05 14:35:35 volumioaz sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-05 14:34 Dec 05 14:35:35 volumioaz sudo[2804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 05 14:35:35 volumioaz ntpd[2793]: Soliciting pool server 178.63.52.50 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"