-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-19 18:30:04 CET. -- Jan 19 18:29:09 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:09 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:09 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:09 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:09 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:19 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces state update: player Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: ControllerMpd::getState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:29:19 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:29:19 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: ControllerMpd::pushState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::servicePushState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: CoreStateMachine::pushState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:19 pioneer-sx-950 volumio[849]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: ------------------------------ 5ms Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:19 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:19 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:19 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: Clearing queue after UPNP request Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::ClearQueue Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::stop Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::stPlaybackTimer Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::updateTrackBlock Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrackBlock Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::pushState Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioPushState Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: MRS: Pushing multiroomSync output update for this device Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: MRS: Pushing multiroomSync output Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::serviceStop Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: ControllerMpd::stop Jan 19 18:29:21 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::clearPlayQueue Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::saveQueue Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioPushState Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: MRS: Pushing multiroomSync output update for this device Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: MRS: Pushing multiroomSync output Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioPushQueue Jan 19 18:29:21 pioneer-sx-950 volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:21 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: sendMpdCommand stop took 35 milliseconds Jan 19 18:29:21 pioneer-sx-950 volumio[849]: error: updateQueue error: null Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: ------------------------------ 3ms Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: Starting UPNP Playback Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: Preparing playback through UPNP Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:21 pioneer-sx-950 volumio[849]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 2ms Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 1ms Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 2ms Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 1ms Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:22 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 3ms Jan 19 18:29:22 pioneer-sx-950 volumio[849]: info: ------------------------------ 2ms Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:24 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces state update: player Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::getState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:24 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces state update: player Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand status took 3 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::getState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::pushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::servicePushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: In UPNP mode Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","artist":null,"album":" Home Assistant","uri":"http://192.168.1.8:8097/flow/media_player.openhome_uuid_eb4cffa3_0d37_3883_15fb_dca63222c181/e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","trackType":""} Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: CURRENT POSITION 0 Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState stateService play Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState currentStatus stop Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ------------------------------ 8ms Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::pushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::servicePushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: In UPNP mode Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","artist":null,"album":" Home Assistant","uri":"http://192.168.1.8:8097/flow/media_player.openhome_uuid_eb4cffa3_0d37_3883_15fb_dca63222c181/e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","trackType":""} Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: CURRENT POSITION 0 Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState stateService play Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Received an update from plugin. extracting info from payload Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::pushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:24 pioneer-sx-950 volumio[849]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ------------------------------ 8ms Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:24 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces system playlist update Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Ignoring MPD Status Update Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:24 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces state update: player Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::getState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ------------------------------ 2ms Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand status took 2 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ControllerMpd::pushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::servicePushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: In UPNP mode Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","artist":"Music Assistant","album":null,"uri":"http://192.168.1.8:8097/flow/media_player.openhome_uuid_eb4cffa3_0d37_3883_15fb_dca63222c181/e979afcaca76459ba4400ca9e74be3e6.mp3?ts=1737307758","trackType":""} Jan 19 18:29:24 pioneer-sx-950 volumio[849]: verbose: CURRENT POSITION 0 Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState stateService play Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::syncState currentStatus play Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: Received an update from plugin. extracting info from payload Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CoreStateMachine::pushState Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:24 pioneer-sx-950 volumio[849]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:24 pioneer-sx-950 volumio[849]: info: ------------------------------ 6ms Jan 19 18:29:29 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:29 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:29 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:29 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:29 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:39 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:39 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:39 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:39 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:49 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:49 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:49 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:49 pioneer-sx-950 volumio[849]: info: Listing playlists Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: Jan 19 18:29:52 pioneer-sx-950 volumio[849]: ---------------------------- MPD announces state update: player Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: ControllerMpd::getState Jan 19 18:29:52 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand status Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: sendMpdCommand status took 1 milliseconds Jan 19 18:29:52 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseState Jan 19 18:29:52 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 19 18:29:52 pioneer-sx-950 volumio[849]: verbose: ControllerMpd::parseTrackInfo Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: ControllerMpd::pushState Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::servicePushState Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: CoreStateMachine::pushState Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:52 pioneer-sx-950 volumio[849]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:52 pioneer-sx-950 volumio[849]: info: ------------------------------ 5ms Jan 19 18:29:52 pioneer-sx-950 volumiologrotate[632]: ls: cannot access '/var/log/samba/log.wb-PIONEER': No such file or directory Jan 19 18:29:52 pioneer-sx-950 volumiologrotate[632]: ls: cannot access 'SX-950': No such file or directory Jan 19 18:29:59 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:29:59 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:29:59 pioneer-sx-950 volumio[849]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'name' of undefined Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at RESTApiPlayback.playbackGetState (/volumio/app/plugins/user_interface/rest_api/playback.js:262:37) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:281:22 Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at jsonParser (/volumio/node_modules/body-parser/lib/types/json.js:110:7) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at /volumio/node_modules/express/lib/router/index.js:284:7 Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Jan 19 18:29:59 pioneer-sx-950 volumio[849]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Jan 19 18:30:02 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:02+01:00" level=debug msg="obtained new client token: AADQ8tvKeiQgmjOAskN7QhsDl9oRB5HmIdmotQ7fDypZAdqQyNZ7Sv+J3ayLvH5sI9ypuXtVqCjotiIMnkNOv9OLCQq7YaclcVrF3+9kvugbql+kFxa14vMrf5YOYEYo4QXks/YElWCAipeNNZJ9TVmdtuWlkMfokWR5oQXxXsDhJgS51oy3XEnPrTRQ3joIdc9iKmDBJdC+JrMRjdDshvPUXUQWkwwj7SoAbO5Rfkx3T0KrDzCerk7hJd2AQ6k=" Jan 19 18:30:02 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Jan 19 18:30:02 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:02+01:00" level=debug msg="completed keyexchange" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="completed challenge" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="authenticated as ohjay93" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="authenticated as ohjay93" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="dealer connection opened" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=info msg="accepted zeroconf user ohjay93 from DAGROS" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="autoplay enabled: false" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="received connection id: ZGFhZjFhOTEtZGQzNC00OTI4LTlkMjctODMwOTIxNzhlZmFkK2RlYWxlcit0Y3A6Ly8wYWNhNTk5Mi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzNFRDlCN0REQzFDNTA3NjU1RTIwNkNEQjJBMzg2Q0MyNjM1RDY5MkY2QjBGNUM2QTFENzUwNDEwQjQ4QzQxMA==" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="handling transfer player command from 42d251f875b116faba10195696e06fd539ab6854" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="resolved context of track" uri="spotify:artist:0aeLcja6hKzb7Uz2ou7ulP" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=trace msg="fetched new page 0 with 33 items (list: 33)" uri="spotify:artist:0aeLcja6hKzb7Uz2ou7ulP" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="loading track (paused: true, position: 51851ms)" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=trace msg="emitting websocket event: will_play" Jan 19 18:30:03 pioneer-sx-950 volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:12je8giC2S9smUHJ81tpiL","play_origin":"artist"}} Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2811" Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: [2025/01/19 18:30:03.760556, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: This response was from IP 192.168.1.8, reporting an IP address of 192.168.1.33. Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: [2025/01/19 18:30:03.760889, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Jan 19 18:30:03 pioneer-sx-950 nmbd[788]: This response was from IP 192.168.1.8, reporting an IP address of 192.168.1.33. Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="selected format OGG_VORBIS_320 (4c77061c79ef2f992763b97c81b864ebf37359cb)" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="requested aes key for file 4c77061c79ef2f992763b97c81b864ebf37359cb, gid: 12je8giC2S9smUHJ81tpiL" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="fetched first chunk of 20, total size is 10054676 bytes" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:03 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:03+01:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 5/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 6/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 4/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=trace msg="seek to 51851ms (diff: 129ms, samples: 2286629, bytes: 2187531)" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="fetched chunk 7/19, size: 524288" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="created new output device" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=info msg="loaded track \"5 Years Time\" (paused: true, position: 51851ms, duration: 214933ms, prefetched: false)" uri="spotify:track:12je8giC2S9smUHJ81tpiL" Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: [2025/01/19 18:30:04.169126, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: This response was from IP 192.168.1.33, reporting an IP address of 192.168.1.33. Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: [2025/01/19 18:30:04.169401, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: query_name_response: Multiple (5) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Jan 19 18:30:04 pioneer-sx-950 nmbd[788]: This response was from IP 192.168.1.8, reporting an IP address of 192.168.1.33. Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=trace msg="emitting websocket event: metadata" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=trace msg="emitting websocket event: active" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="sending successful reply for dealer request" Jan 19 18:30:04 pioneer-sx-950 volumio[849]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:12je8giC2S9smUHJ81tpiL","name":"5 Years Time","artist_names":["Noah And The Whale"],"album_name":"Peaceful, The World Lays Me Down","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02149ceb0d91267627dcf8fb6c","position":51851,"duration":214933,"release_date":"year:2008 month:1 day:1","track_number":7,"disc_number":1}} Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 19 18:30:04 pioneer-sx-950 volumio[849]: SPOTIFY: received: {"type":"active","data":null} Jan 19 18:30:04 pioneer-sx-950 volumio[849]: info: Aligning Spotify Volume to Volumio Volume Jan 19 18:30:04 pioneer-sx-950 volumio[849]: info: CoreCommandRouter::volumioGetState Jan 19 18:30:04 pioneer-sx-950 volumio[849]: info: CorePlayQueue::getTrack 0 Jan 19 18:30:04 pioneer-sx-950 volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 18:30:04 pioneer-sx-950 volumio[849]: TypeError: Cannot read property 'name' of undefined Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at ControllerSpotify.alignSpotifyVolumeToVolumioVolume (/data/plugins/music_service/spop/index.js:595:36) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:285:18) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at WebSocket.emit (events.js:315:20) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.emit (events.js:315:20) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at writeOrBuffer (internal/streams/writable.js:358:12) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: at Socket.emit (events.js:315:20) Jan 19 18:30:04 pioneer-sx-950 volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 19 18:30:04 pioneer-sx-950 go-librespot[1108]: time="2025-01-19T18:30:04+01:00" level=trace msg="emitting websocket event: paused" Jan 19 18:30:04 pioneer-sx-950 sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 18:29 Jan 19 18:30:04 pioneer-sx-950 sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"