-- Logs begin at Sun 2024-08-11 10:39:17 CST, end at Sun 2024-10-06 10:32:16 CST. -- Oct 06 10:31:02 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:03 volumio volumio[936]: info: Clearing queue after UPNP request Oct 06 10:31:03 volumio volumio[936]: info: CoreStateMachine::ClearQueue Oct 06 10:31:03 volumio volumio[936]: info: CoreStateMachine::stop Oct 06 10:31:03 volumio volumio[936]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:31:03 volumio volumio[936]: info: CorePlayQueue::clearPlayQueue Oct 06 10:31:03 volumio volumio[936]: info: CorePlayQueue::saveQueue Oct 06 10:31:03 volumio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 06 10:31:03 volumio volumio[936]: info: CoreCommandRouter::volumioPushQueue Oct 06 10:31:03 volumio volumio[936]: info: Oct 06 10:31:03 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:03 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:03 volumio volumio[936]: error: updateQueue error: null Oct 06 10:31:03 volumio volumio[936]: info: ------------------------------ 3ms Oct 06 10:31:03 volumio volumio[936]: info: Starting UPNP Playback Oct 06 10:31:03 volumio volumio[936]: info: Preparing playback through UPNP Oct 06 10:31:03 volumio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 06 10:31:03 volumio volumio[936]: info: CorePlayQueue::getTrack 0 Oct 06 10:31:03 volumio volumio[936]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 10:31:03 volumio openvpn[784]: TCP/UDP: Preserving recently used remote address: [AF_INET]14.136.32.17:1199 Oct 06 10:31:03 volumio openvpn[784]: Socket Buffers: R=[180224->180224] S=[180224->180224] Oct 06 10:31:03 volumio openvpn[784]: UDP link local: (not bound) Oct 06 10:31:03 volumio openvpn[784]: UDP link remote: [AF_INET]14.136.32.17:1199 Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 2ms Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 2ms Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 3ms Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 4ms Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 3ms Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces system playlist update Oct 06 10:31:04 volumio volumio[936]: info: Ignoring MPD Status Update Oct 06 10:31:04 volumio volumio[936]: info: ------------------------------ 2ms Oct 06 10:31:04 volumio volumio[936]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 06 10:31:04 volumio volumio[936]: LoungeScreenDisconnected { Oct 06 10:31:04 volumio volumio[936]: AID: null, Oct 06 10:31:04 volumio volumio[936]: name: 'loungeScreenDisconnected', Oct 06 10:31:04 volumio volumio[936]: payload: {} Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 06 10:31:04 volumio volumio[936]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 06 10:31:04 volumio volumio[936]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 06 10:31:04 volumio volumio[936]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 06 10:31:04 volumio volumio[936]: at new Promise () Oct 06 10:31:04 volumio volumio[936]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 06 10:31:04 volumio volumio[936]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 06 10:31:04 volumio volumio[936]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: at async Promise.all (index 0) Oct 06 10:31:04 volumio volumio[936]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Oct 06 10:31:04 volumio volumio[936]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 06 10:31:04 volumio volumio[936]: error: [yt-cast-receiver] Failed to start receiver: Oct 06 10:31:04 volumio volumio[936]: (AppError) Failed to start YouTubeApp Oct 06 10:31:04 volumio volumio[936]: --->(SessionError) (YouTube) Failed to establish session Oct 06 10:31:04 volumio volumio[936]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 06 10:31:04 volumio volumio[936]: Error info: { Oct 06 10:31:04 volumio volumio[936]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 142.251.215.238:443 Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: AppError: Failed to start YouTubeApp Oct 06 10:31:04 volumio volumio[936]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 06 10:31:04 volumio volumio[936]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 06 10:31:04 volumio volumio[936]: LoungeScreenDisconnected { Oct 06 10:31:04 volumio volumio[936]: AID: null, Oct 06 10:31:04 volumio volumio[936]: name: 'loungeScreenDisconnected', Oct 06 10:31:04 volumio volumio[936]: payload: {} Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 06 10:31:04 volumio volumio[936]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 06 10:31:04 volumio volumio[936]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Oct 06 10:31:04 volumio volumio[936]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Oct 06 10:31:04 volumio volumio[936]: at new Promise () Oct 06 10:31:04 volumio volumio[936]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Oct 06 10:31:04 volumio volumio[936]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Oct 06 10:31:04 volumio volumio[936]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Oct 06 10:31:04 volumio volumio[936]: at Array.forEach () Oct 06 10:31:04 volumio volumio[936]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 06 10:31:04 volumio volumio[936]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Oct 06 10:31:04 volumio volumio[936]: LoungeScreenDisconnected { Oct 06 10:31:04 volumio volumio[936]: AID: null, Oct 06 10:31:04 volumio volumio[936]: name: 'loungeScreenDisconnected', Oct 06 10:31:04 volumio volumio[936]: payload: {} Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 06 10:31:04 volumio volumio[936]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 06 10:31:04 volumio volumio[936]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 06 10:31:04 volumio volumio[936]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: error: [ytcr] Failed to start plugin: Oct 06 10:31:04 volumio volumio[936]: (AppError) Failed to start YouTubeApp Oct 06 10:31:04 volumio volumio[936]: --->(SessionError) (YouTube) Failed to establish session Oct 06 10:31:04 volumio volumio[936]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Oct 06 10:31:04 volumio volumio[936]: Error info: { Oct 06 10:31:04 volumio volumio[936]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: connect ETIMEDOUT 142.251.215.238:443 Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: AppError: Failed to start YouTubeApp Oct 06 10:31:04 volumio volumio[936]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Oct 06 10:31:04 volumio volumio[936]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Oct 06 10:31:04 volumio volumio[936]: LoungeScreenDisconnected { Oct 06 10:31:04 volumio volumio[936]: AID: null, Oct 06 10:31:04 volumio volumio[936]: name: 'loungeScreenDisconnected', Oct 06 10:31:04 volumio volumio[936]: payload: {} Oct 06 10:31:04 volumio volumio[936]: } Oct 06 10:31:04 volumio volumio[936]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Oct 06 10:31:04 volumio volumio[936]: Stack trace: Oct 06 10:31:04 volumio volumio[936]: IncompleteAPIDataError: Missing data required to construct query string from bind params Oct 06 10:31:04 volumio volumio[936]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Oct 06 10:31:04 volumio volumio[936]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Oct 06 10:31:04 volumio volumio[936]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Oct 06 10:31:04 volumio volumio[936]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Oct 06 10:31:04 volumio volumio[936]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Oct 06 10:31:04 volumio volumio[936]: at runMicrotasks () Oct 06 10:31:04 volumio volumio[936]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 06 10:31:04 volumio volumio[936]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Oct 06 10:31:04 volumio volumio[936]: info: Oct 06 10:31:04 volumio volumio[936]: ---------------------------- MPD announces state update: player Oct 06 10:31:04 volumio volumio[936]: info: ControllerMpd::getState Oct 06 10:31:04 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:31:05 volumio volumio[936]: info: Oct 06 10:31:05 volumio volumio[936]: ---------------------------- MPD announces state update: player Oct 06 10:31:05 volumio volumio[936]: info: sendMpdCommand status took 494 milliseconds Oct 06 10:31:05 volumio volumio[936]: info: ControllerMpd::getState Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::parseState Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:31:05 volumio volumio[936]: info: sendMpdCommand status took 6 milliseconds Oct 06 10:31:05 volumio volumio[936]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::parseState Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:31:05 volumio volumio[936]: info: ControllerMpd::pushState Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::servicePushState Oct 06 10:31:05 volumio volumio[936]: verbose: In UPNP mode Oct 06 10:31:05 volumio volumio[936]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"笑看风云","artist":"姚璎格","album":"粤韵","uri":"http://192.168.50.214:12729/tidal/263110973.flac","trackType":"tidal"} Oct 06 10:31:05 volumio volumio[936]: verbose: CURRENT POSITION 0 Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::syncState stateService play Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:31:05 volumio volumio[936]: info: ------------------------------ 515ms Oct 06 10:31:05 volumio volumio[936]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 06 10:31:05 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:31:05 volumio volumio[936]: info: ControllerMpd::pushState Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::servicePushState Oct 06 10:31:05 volumio volumio[936]: verbose: In UPNP mode Oct 06 10:31:05 volumio volumio[936]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"笑看风云","artist":"姚璎格","album":"粤韵","uri":"http://192.168.50.214:12729/tidal/263110973.flac","trackType":"tidal"} Oct 06 10:31:05 volumio volumio[936]: verbose: CURRENT POSITION 0 Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::syncState stateService play Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:31:05 volumio volumio[936]: info: Received an update from plugin. extracting info from payload Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::pushState Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::pushState Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 06 10:31:05 volumio volumio[936]: info: ------------------------------ 77ms Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::getQueue Oct 06 10:31:05 volumio volumio[936]: info: CorePlayQueue::getQueue Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 06 10:31:05 volumio volumio[936]: info: CoreStateMachine::getQueue Oct 06 10:31:05 volumio volumio[936]: info: CorePlayQueue::getQueue Oct 06 10:31:05 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 10:31:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 10:31:08 volumio volumio[936]: info: Discovery: Getting this device information Oct 06 10:31:08 volumio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 06 10:31:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 10:31:18 volumio volumio[936]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 06 10:31:18 volumio volumio[936]: info: Retrying Login Due to Network Error Oct 06 10:31:28 volumio volumio[936]: info: MyVolumio login type: Token Oct 06 10:31:30 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:31 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:35 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:37 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand update Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:31:37 volumio volumio[936]: info: Oct 06 10:31:37 volumio volumio[936]: ---------------------------- MPD announces state update: update Oct 06 10:31:37 volumio volumio[936]: info: sendMpdCommand update took 5 milliseconds Oct 06 10:31:37 volumio volumio[936]: info: ControllerMpd::getState Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand status Oct 06 10:31:37 volumio volumio[936]: info: sendMpdCommand status took 4 milliseconds Oct 06 10:31:37 volumio volumio[936]: info: sendMpdCommand status took 3 milliseconds Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::parseState Oct 06 10:31:37 volumio volumio[936]: info: Command Router : Notfying DB Updatetrue Oct 06 10:31:37 volumio volumio[936]: info: CoreCommandRouter::Close All Modals sent Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::parseState Oct 06 10:31:37 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 10:31:38 volumio volumio[936]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 06 10:31:38 volumio volumio[936]: verbose: ControllerMpd::parseTrackInfo Oct 06 10:31:38 volumio volumio[936]: info: ControllerMpd::pushState Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::servicePushState Oct 06 10:31:38 volumio volumio[936]: verbose: In UPNP mode Oct 06 10:31:38 volumio volumio[936]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33587,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"笑看风云","artist":"姚璎格","album":"粤韵","uri":"http://192.168.50.214:12729/tidal/263110973.flac","trackType":"tidal"} Oct 06 10:31:38 volumio volumio[936]: verbose: CURRENT POSITION 0 Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::syncState stateService play Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:31:38 volumio volumio[936]: info: Received an update from plugin. extracting info from payload Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::pushState Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::pushState Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::volumioPushState Oct 06 10:31:38 volumio volumio[936]: info: ------------------------------ 130ms Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::getQueue Oct 06 10:31:38 volumio volumio[936]: info: CorePlayQueue::getQueue Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::volumioGetQueue Oct 06 10:31:38 volumio volumio[936]: info: CoreStateMachine::getQueue Oct 06 10:31:38 volumio volumio[936]: info: CorePlayQueue::getQueue Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 10:31:38 volumio volumio[936]: info: Discovery: Getting this device information Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 06 10:31:38 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 10:31:39 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:43 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:44 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 06 10:31:44 volumio volumio[936]: verbose: ControllerMpd::sendMpdCommand update Oct 06 10:31:44 volumio volumio[936]: info: sendMpdCommand update took 2 milliseconds Oct 06 10:31:47 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:51 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:55 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:31:58 volumio volumio[936]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 06 10:31:58 volumio volumio[936]: info: Retrying Login Due to Network Error Oct 06 10:32:00 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:32:03 volumio openvpn[784]: TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity) Oct 06 10:32:03 volumio openvpn[784]: TLS Error: TLS handshake failed Oct 06 10:32:03 volumio openvpn[784]: SIGUSR1[soft,tls-error] received, process restarting Oct 06 10:32:03 volumio openvpn[784]: Restart pause, 40 second(s) Oct 06 10:32:04 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:32:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 10:32:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 10:32:08 volumio volumio[936]: info: Discovery: Getting this device information Oct 06 10:32:08 volumio volumio[936]: info: CoreCommandRouter::volumioGetState Oct 06 10:32:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 10:32:08 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:32:08 volumio volumio[936]: info: MyVolumio login type: Token Oct 06 10:32:12 volumio volumio[936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 10:32:15 volumio volumio[936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:32:15 volumio volumio[936]: Error: connect ETIMEDOUT 199.16.158.190:443 Oct 06 10:32:15 volumio volumio[936]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 06 10:32:15 volumio volumio[936]: errno: -110, Oct 06 10:32:15 volumio volumio[936]: code: 'ETIMEDOUT', Oct 06 10:32:15 volumio volumio[936]: syscall: 'connect', Oct 06 10:32:15 volumio volumio[936]: address: '199.16.158.190', Oct 06 10:32:15 volumio volumio[936]: port: 443 Oct 06 10:32:15 volumio volumio[936]: } Oct 06 10:32:15 volumio volumio[936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:32:16 volumio sudo[2778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 10:31 Oct 06 10:32:16 volumio sudo[2778]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"