-- Logs begin at Sat 2024-10-26 11:49:05 CEST, end at Sun 2024-10-27 16:55:46 CET. -- Oct 27 16:54:03 volumio volumio[650]: error: [yt-cast-receiver] (YouTube Music) RPC connection terminated due to error: Oct 27 16:54:03 volumio volumio[650]: (BadResponseError) RPC connection request returned bad response Oct 27 16:54:03 volumio volumio[650]: Error info: { Oct 27 16:54:03 volumio volumio[650]: url: 'https://www.youtube.com/api/lounge/bc/bind?AID=257&CI=0&RID=rpc&SID=3D3B333236074E0B&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=EJSHZ9GkVDjJ7VUZpaCIy_SYSg53pXT8&id=18d20c20-c6b4-4fd9-aa5c-c1028a14f523&loungeIdToken=AGdO5p9NuG3RCOU8nimiSRU1mbhDeyqdiqGOLt0iwd8KroKUXvbNybTmBhcuzcARfAJaZpZgqBEsWtDlmx9zQ_H2g2aPDKuO9UHpahj-SIppdSYzqejYPxQ&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=8756b18d9c3d', Oct 27 16:54:03 volumio volumio[650]: response: Response { Oct 27 16:54:03 volumio volumio[650]: size: 0, Oct 27 16:54:03 volumio volumio[650]: timeout: 0, Oct 27 16:54:03 volumio volumio[650]: [Symbol(Body internals)]: { Oct 27 16:54:03 volumio volumio[650]: body: Gunzip { Oct 27 16:54:03 volumio volumio[650]: _writeState: Uint32Array(2) [ 0, 0 ], Oct 27 16:54:03 volumio volumio[650]: _readableState: ReadableState { Oct 27 16:54:03 volumio volumio[650]: objectMode: false, Oct 27 16:54:03 volumio volumio[650]: highWaterMark: 16384, Oct 27 16:54:03 volumio volumio[650]: buffer: BufferList { head: null, tail: null, length: 0 }, Oct 27 16:54:03 volumio volumio[650]: length: 0, Oct 27 16:54:03 volumio volumio[650]: pipes: [], Oct 27 16:54:03 volumio volumio[650]: flowing: null, Oct 27 16:54:03 volumio volumio[650]: ended: false, Oct 27 16:54:03 volumio volumio[650]: endEmitted: false, Oct 27 16:54:03 volumio volumio[650]: reading: false, Oct 27 16:54:03 volumio volumio[650]: sync: false, Oct 27 16:54:03 volumio volumio[650]: needReadable: false, Oct 27 16:54:03 volumio volumio[650]: emittedReadable: false, Oct 27 16:54:03 volumio volumio[650]: readableListening: false, Oct 27 16:54:03 volumio volumio[650]: resumeScheduled: false, Oct 27 16:54:03 volumio volumio[650]: errorEmitted: false, Oct 27 16:54:03 volumio volumio[650]: emitClose: true, Oct 27 16:54:03 volumio volumio[650]: autoDestroy: true, Oct 27 16:54:03 volumio volumio[650]: destroyed: false, Oct 27 16:54:03 volumio volumio[650]: errored: null, Oct 27 16:54:03 volumio volumio[650]: closed: false, Oct 27 16:54:03 volumio volumio[650]: closeEmitted: false, Oct 27 16:54:03 volumio volumio[650]: defaultEncoding: 'utf8', Oct 27 16:54:03 volumio volumio[650]: awaitDrainWriters: null, Oct 27 16:54:03 volumio volumio[650]: multiAwaitDrain: false, Oct 27 16:54:03 volumio volumio[650]: readingMore: false, Oct 27 16:54:03 volumio volumio[650]: decoder: null, Oct 27 16:54:03 volumio volumio[650]: encoding: null, Oct 27 16:54:03 volumio volumio[650]: [Symbol(kPaused)]: null Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: _events: [Object: null prototype] { Oct 27 16:54:03 volumio volumio[650]: prefinish: [Function: prefinish], Oct 27 16:54:03 volumio volumio[650]: unpipe: [Function: onunpipe], Oct 27 16:54:03 volumio volumio[650]: error: [ [Function: onerror], [Function (anonymous)] ], Oct 27 16:54:03 volumio volumio[650]: close: [Function: bound onceWrapper] { Oct 27 16:54:03 volumio volumio[650]: listener: [Function: onclose] Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: finish: [Function: bound onceWrapper] { Oct 27 16:54:03 volumio volumio[650]: listener: [Function: onfinish] Oct 27 16:54:03 volumio volumio[650]: } Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: _eventsCount: 5, Oct 27 16:54:03 volumio volumio[650]: _maxListeners: undefined, Oct 27 16:54:03 volumio volumio[650]: _writableState: WritableState { Oct 27 16:54:03 volumio volumio[650]: objectMode: false, Oct 27 16:54:03 volumio volumio[650]: highWaterMark: 16384, Oct 27 16:54:03 volumio volumio[650]: finalCalled: false, Oct 27 16:54:03 volumio volumio[650]: needDrain: false, Oct 27 16:54:03 volumio volumio[650]: ending: false, Oct 27 16:54:03 volumio volumio[650]: ended: false, Oct 27 16:54:03 volumio volumio[650]: finished: false, Oct 27 16:54:03 volumio volumio[650]: destroyed: false, Oct 27 16:54:03 volumio volumio[650]: decodeStrings: true, Oct 27 16:54:03 volumio volumio[650]: defaultEncoding: 'utf8', Oct 27 16:54:03 volumio volumio[650]: length: 0, Oct 27 16:54:03 volumio volumio[650]: writing: false, Oct 27 16:54:03 volumio volumio[650]: corked: 0, Oct 27 16:54:03 volumio volumio[650]: sync: true, Oct 27 16:54:03 volumio volumio[650]: bufferProcessing: false, Oct 27 16:54:03 volumio volumio[650]: onwrite: [Function: bound onwrite], Oct 27 16:54:03 volumio volumio[650]: writecb: null, Oct 27 16:54:03 volumio volumio[650]: writelen: 0, Oct 27 16:54:03 volumio volumio[650]: afterWriteTickInfo: null, Oct 27 16:54:03 volumio volumio[650]: buffered: [], Oct 27 16:54:03 volumio volumio[650]: bufferedIndex: 0, Oct 27 16:54:03 volumio volumio[650]: allBuffers: true, Oct 27 16:54:03 volumio volumio[650]: allNoop: true, Oct 27 16:54:03 volumio volumio[650]: pendingcb: 0, Oct 27 16:54:03 volumio volumio[650]: prefinished: false, Oct 27 16:54:03 volumio volumio[650]: errorEmitted: false, Oct 27 16:54:03 volumio volumio[650]: emitClose: true, Oct 27 16:54:03 volumio volumio[650]: autoDestroy: true, Oct 27 16:54:03 volumio volumio[650]: errored: null, Oct 27 16:54:03 volumio volumio[650]: closed: false Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: allowHalfOpen: true, Oct 27 16:54:03 volumio volumio[650]: bytesWritten: 0, Oct 27 16:54:03 volumio volumio[650]: _handle: Zlib { Oct 27 16:54:03 volumio volumio[650]: onerror: [Function: zlibOnError], Oct 27 16:54:03 volumio volumio[650]: [Symbol(owner_symbol)]: [Circular *1] Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: _outBuffer: , Oct 27 16:54:03 volumio volumio[650]: _outOffset: 0, Oct 27 16:54:03 volumio volumio[650]: _chunkSize: 16384, Oct 27 16:54:03 volumio volumio[650]: _defaultFlushFlag: 2, Oct 27 16:54:03 volumio volumio[650]: _finishFlushFlag: 2, Oct 27 16:54:03 volumio volumio[650]: _defaultFullFlushFlag: 3, Oct 27 16:54:03 volumio volumio[650]: _info: undefined, Oct 27 16:54:03 volumio volumio[650]: _maxOutputLength: 1073741823, Oct 27 16:54:03 volumio volumio[650]: _level: -1, Oct 27 16:54:03 volumio volumio[650]: _strategy: 0, Oct 27 16:54:03 volumio volumio[650]: [Symbol(kCapture)]: false, Oct 27 16:54:03 volumio volumio[650]: [Symbol(kTransformState)]: { Oct 27 16:54:03 volumio volumio[650]: afterTransform: [Function: bound afterTransform], Oct 27 16:54:03 volumio volumio[650]: needTransform: false, Oct 27 16:54:03 volumio volumio[650]: transforming: false, Oct 27 16:54:03 volumio volumio[650]: writecb: null, Oct 27 16:54:03 volumio volumio[650]: writechunk: null, Oct 27 16:54:03 volumio volumio[650]: writeencoding: null Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: [Symbol(kError)]: null Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: disturbed: false, Oct 27 16:54:03 volumio volumio[650]: error: null Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: [Symbol(Response internals)]: { Oct 27 16:54:03 volumio volumio[650]: url: 'https://www.youtube.com/api/lounge/bc/bind?AID=257&CI=0&RID=rpc&SID=3D3B333236074E0B&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=EJSHZ9GkVDjJ7VUZpaCIy_SYSg53pXT8&id=18d20c20-c6b4-4fd9-aa5c-c1028a14f523&loungeIdToken=AGdO5p9NuG3RCOU8nimiSRU1mbhDeyqdiqGOLt0iwd8KroKUXvbNybTmBhcuzcARfAJaZpZgqBEsWtDlmx9zQ_H2g2aPDKuO9UHpahj-SIppdSYzqejYPxQ&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=8756b18d9c3d', Oct 27 16:54:03 volumio volumio[650]: status: 400, Oct 27 16:54:03 volumio volumio[650]: statusText: 'Unknown SID', Oct 27 16:54:03 volumio volumio[650]: headers: Headers { Oct 27 16:54:03 volumio volumio[650]: [Symbol(map)]: [Object: null prototype] { Oct 27 16:54:03 volumio volumio[650]: 'cache-control': [ 'no-cache, no-store, max-age=0, must-revalidate' ], Oct 27 16:54:03 volumio volumio[650]: pragma: [ 'no-cache' ], Oct 27 16:54:03 volumio volumio[650]: expires: [ 'Mon, 01 Jan 1990 00:00:00 GMT' ], Oct 27 16:54:03 volumio volumio[650]: date: [ 'Sun, 27 Oct 2024 15:54:03 GMT' ], Oct 27 16:54:03 volumio volumio[650]: 'content-type': [ 'text/html; charset=UTF-8' ], Oct 27 16:54:03 volumio volumio[650]: 'content-encoding': [ 'gzip' ], Oct 27 16:54:03 volumio volumio[650]: 'x-content-type-options': [ 'nosniff' ], Oct 27 16:54:03 volumio volumio[650]: 'x-xss-protection': [ '1; mode=block' ], Oct 27 16:54:03 volumio volumio[650]: server: [ 'GSE' ], Oct 27 16:54:03 volumio volumio[650]: 'alt-svc': [ 'h3=":443"; ma=2592000,h3-29=":443"; ma=2592000' ], Oct 27 16:54:03 volumio volumio[650]: connection: [ 'close' ], Oct 27 16:54:03 volumio volumio[650]: 'transfer-encoding': [ 'chunked' ] Oct 27 16:54:03 volumio volumio[650]: } Oct 27 16:54:03 volumio volumio[650]: }, Oct 27 16:54:03 volumio volumio[650]: counter: 0 Oct 27 16:54:03 volumio volumio[650]: } Oct 27 16:54:03 volumio volumio[650]: } Oct 27 16:54:03 volumio volumio[650]: } Oct 27 16:54:03 volumio volumio[650]: Stack trace: Oct 27 16:54:03 volumio volumio[650]: BadResponseError: RPC connection request returned bad response Oct 27 16:54:03 volumio volumio[650]: at RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/RPCConnection.js:129:11) Oct 27 16:54:03 volumio volumio[650]: at runMicrotasks () Oct 27 16:54:03 volumio volumio[650]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 27 16:54:03 volumio volumio[650]: at async RPCConnection._RPCConnection_handleDisconnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/RPCConnection.js:145:13) Oct 27 16:54:09 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 16:54:09 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 16:54:09 volumio volumio[650]: info: Discovery: Getting this device information Oct 27 16:54:09 volumio volumio[650]: info: CoreCommandRouter::volumioGetState Oct 27 16:54:09 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:54:09 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 16:55:12 volumio volumio[650]: info: Clearing queue after UPNP request Oct 27 16:55:12 volumio volumio[650]: info: CoreStateMachine::ClearQueue Oct 27 16:55:12 volumio volumio[650]: info: CoreStateMachine::stop Oct 27 16:55:12 volumio volumio[650]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 16:55:12 volumio volumio[650]: info: CorePlayQueue::clearPlayQueue Oct 27 16:55:12 volumio volumio[650]: info: CorePlayQueue::saveQueue Oct 27 16:55:12 volumio volumio[650]: info: CoreCommandRouter::volumioPushState Oct 27 16:55:12 volumio volumio[650]: info: CoreCommandRouter::volumioPushQueue Oct 27 16:55:12 volumio volumio[650]: error: Upnp client error: Error: This socket has been ended by the other party Oct 27 16:55:14 volumio volumio[650]: info: Starting UPNP Playback Oct 27 16:55:14 volumio volumio[650]: info: Preparing playback through UPNP Oct 27 16:55:14 volumio volumio[650]: info: CoreCommandRouter::volumioGetState Oct 27 16:55:14 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:14 volumio volumio[650]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 42ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 31ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 23ms Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 44ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 31ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 22ms Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 44ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 32ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 23ms Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 43ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 31ms Oct 27 16:55:14 volumio volumio[650]: info: ------------------------------ 22ms Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:14 volumio volumio[650]: info: Oct 27 16:55:14 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:14 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:15 volumio volumio[650]: info: Oct 27 16:55:15 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:15 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 44ms Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 31ms Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 23ms Oct 27 16:55:15 volumio volumio[650]: info: Oct 27 16:55:15 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:15 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:15 volumio volumio[650]: info: Oct 27 16:55:15 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:15 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:15 volumio volumio[650]: info: Oct 27 16:55:15 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:15 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 44ms Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 31ms Oct 27 16:55:15 volumio volumio[650]: info: ------------------------------ 22ms Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: sendMpdCommand status took 245 milliseconds Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: sendMpdCommand status took 227 milliseconds Oct 27 16:55:16 volumio volumio[650]: info: sendMpdCommand status took 202 milliseconds Oct 27 16:55:16 volumio volumio[650]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:16 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:16 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:16 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:16 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:16 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:16 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus stop Oct 27 16:55:16 volumio volumio[650]: info: ------------------------------ 465ms Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:16 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:16 volumio volumio[650]: info: Oct 27 16:55:16 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:16 volumio volumio[650]: info: sendMpdCommand status took 318 milliseconds Oct 27 16:55:16 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:16 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 333 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 321 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand status took 145 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand status took 100 milliseconds Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:17 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:17 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:17 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:17 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:17 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:17 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:17 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:17 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:17 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:17 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:17 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:17 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 902ms Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 863ms Oct 27 16:55:17 volumio volumio[650]: info: Oct 27 16:55:17 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:17 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:17 volumio volumio[650]: info: Oct 27 16:55:17 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:17 volumio volumio[650]: info: Oct 27 16:55:17 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:17 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:17 volumio volumio[650]: info: Oct 27 16:55:17 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 552ms Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand status took 477 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 460 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 431 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 415 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 226ms Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand status took 222 milliseconds Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:17 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:17 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:17 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:17 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:17 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:17 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:17 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:17 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:17 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:17 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:17 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:17 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:17 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:17 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:17 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:17 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:17 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:17 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:17 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:17 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:17 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:17 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 1274ms Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 1072ms Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 1041ms Oct 27 16:55:17 volumio volumio[650]: info: ------------------------------ 633ms Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand status took 548 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 422 milliseconds Oct 27 16:55:17 volumio volumio[650]: info: sendMpdCommand playlistinfo took 384 milliseconds Oct 27 16:55:18 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:18 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:18 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:18 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:18 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:18 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:18 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:18 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:18 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:18 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:18 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:18 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:18 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:18 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:18 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:18 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:18 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:18 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:18 volumio volumio[650]: info: ------------------------------ 1251ms Oct 27 16:55:18 volumio volumio[650]: info: ------------------------------ 883ms Oct 27 16:55:18 volumio volumio[650]: info: sendMpdCommand playlistinfo took 213 milliseconds Oct 27 16:55:18 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:18 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:18 volumio volumio[650]: verbose: In UPNP mode Oct 27 16:55:18 volumio volumio[650]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"stream","artist":"RADIO ELETTRICA","album":null,"uri":"http://nr8.newradio.it:9562/stream","trackType":""} Oct 27 16:55:18 volumio volumio[650]: verbose: CURRENT POSITION 0 Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState stateService play Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::syncState currentStatus play Oct 27 16:55:18 volumio volumio[650]: info: Received an update from plugin. extracting info from payload Oct 27 16:55:18 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 27 16:55:18 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:18 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:18 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:18 volumio volumio[650]: info: ------------------------------ 1017ms Oct 27 16:55:20 volumio volumio[650]: info: Oct 27 16:55:20 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand status took 6 milliseconds Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:20 volumio volumio[650]: info: Oct 27 16:55:20 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:20 volumio volumio[650]: info: Oct 27 16:55:20 volumio volumio[650]: ---------------------------- MPD announces state update: player Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::getState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand status Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand playlistinfo took 38 milliseconds Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand status took 26 milliseconds Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand status took 15 milliseconds Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseState Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:20 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:20 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:20 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:20 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:20 volumio volumio[650]: info: ------------------------------ 113ms Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 27 16:55:20 volumio volumio[650]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:20 volumio volumio[650]: verbose: ControllerMpd::parseTrackInfo Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:20 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:20 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:20 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:20 volumio volumio[650]: info: ControllerMpd::pushState Oct 27 16:55:20 volumio volumio[650]: info: CoreCommandRouter::servicePushState Oct 27 16:55:20 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:20 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:20 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:20 volumio volumio[650]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:20 volumio volumio[650]: info: ------------------------------ 131ms Oct 27 16:55:20 volumio volumio[650]: info: ------------------------------ 119ms Oct 27 16:55:23 volumio volumio[650]: info: Clearing queue after UPNP request Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::ClearQueue Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::stop Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::stPlaybackTimer Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::updateTrackBlock Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::getTrackBlock Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::pushState Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:24 volumio volumio[650]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 16:55:24 volumio volumio[650]: info: CoreCommandRouter::volumioPushState Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::serviceStop Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:24 volumio volumio[650]: info: ControllerMpd::stop Oct 27 16:55:24 volumio volumio[650]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::clearPlayQueue Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::saveQueue Oct 27 16:55:24 volumio volumio[650]: info: CoreCommandRouter::volumioPushState Oct 27 16:55:24 volumio volumio[650]: info: CoreCommandRouter::volumioPushQueue Oct 27 16:55:24 volumio volumio[650]: info: Oct 27 16:55:24 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:24 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:24 volumio volumio[650]: info: sendMpdCommand stop took 182 milliseconds Oct 27 16:55:24 volumio volumio[650]: info: Oct 27 16:55:24 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:24 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:24 volumio volumio[650]: info: Oct 27 16:55:24 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:24 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:24 volumio volumio[650]: error: updateQueue error: null Oct 27 16:55:24 volumio volumio[650]: error: updateQueue error: null Oct 27 16:55:24 volumio volumio[650]: error: updateQueue error: null Oct 27 16:55:24 volumio volumio[650]: info: ------------------------------ 90ms Oct 27 16:55:24 volumio volumio[650]: info: ------------------------------ 59ms Oct 27 16:55:24 volumio volumio[650]: info: ------------------------------ 41ms Oct 27 16:55:24 volumio volumio[650]: info: Starting UPNP Playback Oct 27 16:55:24 volumio volumio[650]: info: Preparing playback through UPNP Oct 27 16:55:24 volumio volumio[650]: info: CoreCommandRouter::volumioGetState Oct 27 16:55:24 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:24 volumio volumio[650]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 16:55:24 volumio volumio[650]: info: Oct 27 16:55:24 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:24 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:24 volumio volumio[650]: info: Oct 27 16:55:24 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:24 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 42ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 28ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 20ms Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 41ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 28ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 21ms Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 41ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 29ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 21ms Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 40ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 29ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 20ms Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 41ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 30ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 21ms Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: Oct 27 16:55:25 volumio volumio[650]: ---------------------------- MPD announces system playlist update Oct 27 16:55:25 volumio volumio[650]: info: Ignoring MPD Status Update Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 41ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 29ms Oct 27 16:55:25 volumio volumio[650]: info: ------------------------------ 20ms Oct 27 16:55:27 volumio volumio[650]: info: Starting UPNP Playback Oct 27 16:55:27 volumio volumio[650]: info: Preparing playback through UPNP Oct 27 16:55:27 volumio volumio[650]: info: Consume mode Oct 27 16:55:27 volumio volumio[650]: info: CoreCommandRouter::volumioGetState Oct 27 16:55:27 volumio volumio[650]: info: CorePlayQueue::getTrack 0 Oct 27 16:55:27 volumio volumio[650]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 16:55:27 volumio volumio[650]: TypeError: Cannot read property 'name' of undefined Oct 27 16:55:27 volumio volumio[650]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 27 16:55:27 volumio volumio[650]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 27 16:55:27 volumio volumio[650]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Oct 27 16:55:27 volumio volumio[650]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Oct 27 16:55:27 volumio volumio[650]: at Socket.emit (events.js:315:20) Oct 27 16:55:27 volumio volumio[650]: at addChunk (internal/streams/readable.js:309:12) Oct 27 16:55:27 volumio volumio[650]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 27 16:55:27 volumio volumio[650]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 27 16:55:27 volumio volumio[650]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Oct 27 16:55:28 volumio volumio[650]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 16:55:46 volumio sudo[22727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 16:54 Oct 27 16:55:46 volumio sudo[22727]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"