-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Sun 2024-04-07 04:08:05 EEST. -- Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: Forking 3 albumart workers Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::initPlayerControls Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: Express server listening on port 3000 Apr 07 04:07:00 volumio volumio[6270]: [Metrics] WebUI: 9s 229.50ms Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::resetVolumioState Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::getcurrentVolume Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:00 volumio volumio[6270]: info: MPD Permissions set Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::pushState Apr 07 04:07:00 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::updateTrackBlock Apr 07 04:07:00 volumio volumio[6270]: info: CorePlayQueue::getTrackBlock Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:00 volumio volumio[6270]: info: MPD running with PID6311 Apr 07 04:07:00 volumio volumio[6270]: ,establishing connection Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:00 volumio volumio[6270]: error: updateQueue error: null Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::setRepeat null single undefined Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::pushState Apr 07 04:07:00 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::setRandom null Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::pushState Apr 07 04:07:00 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:00 volumio volumio[6270]: info: Setting Device type: Raspberry PI Apr 07 04:07:00 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:00 volumio volumio[6270]: info: Completed loading Core Plugins Apr 07 04:07:00 volumio volumio[6270]: info: Preparing to generate the ALSA configuration file Apr 07 04:07:00 volumio volumio[6270]: info: CoreStateMachine::pushState Apr 07 04:07:00 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:00 volumio volumio[6270]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:00 volumio volumio[6270]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 07 04:07:00 volumio volumio[6270]: info: Reading ALSA contributions from plugins. Apr 07 04:07:00 volumio volumio[6270]: error: updateQueue error: null Apr 07 04:07:00 volumio volumio[6270]: info: Asound.conf file unchanged, so no further update is needed Apr 07 04:07:00 volumio volumio[6270]: info: Output device has changed, restarting MPD Apr 07 04:07:01 volumio sudo[6372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:01 volumio volumio[6270]: info: Output device has changed, restarting Shairport Sync Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:01 volumio sudo[6372]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:01 volumio sudo[6372]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:01 volumio sudo[6374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:01 volumio sudo[6374]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:01 volumio volumio[6270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:01 volumio volumio[6270]: info: ___________ START PLUGINS ___________ Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:01 volumio volumio[6270]: info: [1712452021214] CoreMusicLibrary::Adding element Media Servers Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:01 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:01 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:01 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:01 volumio volumio[6270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 07 04:07:01 volumio volumio[6270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:01 volumio volumio[6270]: info: [1712452021694] CoreMusicLibrary::Adding element Last_100 Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:01 volumio volumio[6270]: info: [1712452021702] CoreMusicLibrary::Adding element Webradio Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:01 volumio volumio[6270]: info: [squeezelite_mc] Starting proxy server... Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:01 volumio volumio[6270]: info: [1712452021827] CoreMusicLibrary::Adding element YouTube2 Apr 07 04:07:01 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:01 volumio volumio[6270]: Cannot find translation for source YouTube2 Apr 07 04:07:01 volumio volumio[6270]: info: Volumio Calling Home Apr 07 04:07:02 volumio volumio[6270]: Starting albumart workers Apr 07 04:07:02 volumio volumio[6270]: Starting albumart workers Apr 07 04:07:02 volumio volumio[6270]: Starting albumart workers Apr 07 04:07:02 volumio volumio[6270]: info: [squeezelite_mc] Proxy server started on port 43671 Apr 07 04:07:02 volumio volumio[6270]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:02 volumio volumio[6270]: Unhandled rejection Error: No sockets available, cannot start. Apr 07 04:07:02 volumio volumio[6270]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 07 04:07:02 volumio volumio[6270]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 07 04:07:02 volumio volumio[6270]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 07 04:07:02 volumio volumio[6270]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 07 04:07:02 volumio volumio[6270]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 07 04:07:02 volumio volumio[6270]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 07 04:07:02 volumio volumio[6270]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 07 04:07:02 volumio volumio[6270]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 07 04:07:02 volumio volumio[6270]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 07 04:07:02 volumio volumio[6270]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 07 04:07:02 volumio volumio[6270]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 07 04:07:02 volumio volumio[6270]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 07 04:07:02 volumio volumio[6270]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 07 04:07:02 volumio volumio[6270]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 07 04:07:02 volumio volumio[6270]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 07 04:07:02 volumio volumio[6270]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 07 04:07:02 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (8cit1duqm3qpvfigf7d80erhh5): Apr 07 04:07:02 volumio volumio[6270]: (ConnectionError) (YouTube) Connection error in getting lounge token Apr 07 04:07:02 volumio volumio[6270]: Error info: { Apr 07 04:07:02 volumio volumio[6270]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:02 volumio volumio[6270]: } Apr 07 04:07:02 volumio volumio[6270]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:02 volumio volumio[6270]: Stack trace: Apr 07 04:07:02 volumio volumio[6270]: ConnectionError: (YouTube) Connection error in getting lounge token Apr 07 04:07:02 volumio volumio[6270]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:02 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:02 volumio volumio[6270]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:02 volumio volumio[6270]: at async Promise.all (index 0) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:02 volumio volumio[6270]: Going to generate fresh screen Id and try again... Apr 07 04:07:02 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (j9au0cms8rtq71pg20otc3ea7i): Apr 07 04:07:02 volumio volumio[6270]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Apr 07 04:07:02 volumio volumio[6270]: Error info: { Apr 07 04:07:02 volumio volumio[6270]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:02 volumio volumio[6270]: } Apr 07 04:07:02 volumio volumio[6270]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:02 volumio volumio[6270]: Stack trace: Apr 07 04:07:02 volumio volumio[6270]: ConnectionError: (YouTube Music) Connection error in getting lounge token Apr 07 04:07:02 volumio volumio[6270]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:02 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:02 volumio volumio[6270]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:02 volumio volumio[6270]: at async Promise.all (index 1) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:02 volumio volumio[6270]: Going to generate fresh screen Id and try again... Apr 07 04:07:02 volumio volumio[6270]: info: MPD Permissions set Apr 07 04:07:02 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:02 volumio volumio[6270]: LoungeScreenDisconnected { Apr 07 04:07:02 volumio volumio[6270]: AID: null, Apr 07 04:07:02 volumio volumio[6270]: name: 'loungeScreenDisconnected', Apr 07 04:07:02 volumio volumio[6270]: payload: {} Apr 07 04:07:02 volumio volumio[6270]: } Apr 07 04:07:02 volumio volumio[6270]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:02 volumio volumio[6270]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:02 volumio volumio[6270]: Stack trace: Apr 07 04:07:02 volumio volumio[6270]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:02 volumio volumio[6270]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:02 volumio volumio[6270]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:02 volumio volumio[6270]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:02 volumio volumio[6270]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:02 volumio volumio[6270]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:02 volumio volumio[6270]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:02 volumio volumio[6270]: at new Promise () Apr 07 04:07:02 volumio volumio[6270]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:02 volumio volumio[6270]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:02 volumio volumio[6270]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Apr 07 04:07:02 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:02 volumio volumio[6270]: at async Promise.all (index 0) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:02 volumio volumio[6270]: error: [yt-cast-receiver] Failed to start receiver: Apr 07 04:07:02 volumio volumio[6270]: (AppError) Failed to start YouTubeApp Apr 07 04:07:02 volumio volumio[6270]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:02 volumio volumio[6270]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:02 volumio volumio[6270]: Error info: { Apr 07 04:07:02 volumio volumio[6270]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:02 volumio volumio[6270]: } Apr 07 04:07:02 volumio volumio[6270]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:02 volumio volumio[6270]: Stack trace: Apr 07 04:07:02 volumio volumio[6270]: AppError: Failed to start YouTubeApp Apr 07 04:07:02 volumio volumio[6270]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:02 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:02 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:03 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:03 volumio volumio[6270]: LoungeScreenDisconnected { Apr 07 04:07:03 volumio volumio[6270]: AID: null, Apr 07 04:07:03 volumio volumio[6270]: name: 'loungeScreenDisconnected', Apr 07 04:07:03 volumio volumio[6270]: payload: {} Apr 07 04:07:03 volumio volumio[6270]: } Apr 07 04:07:03 volumio volumio[6270]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:03 volumio volumio[6270]: Stack trace: Apr 07 04:07:03 volumio volumio[6270]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:03 volumio volumio[6270]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:03 volumio volumio[6270]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:03 volumio volumio[6270]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:03 volumio volumio[6270]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:03 volumio volumio[6270]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:03 volumio volumio[6270]: at new Promise () Apr 07 04:07:03 volumio volumio[6270]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:03 volumio volumio[6270]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:03 volumio volumio[6270]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Apr 07 04:07:03 volumio volumio[6270]: at Array.forEach () Apr 07 04:07:03 volumio volumio[6270]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Apr 07 04:07:03 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:03 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:03 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:03 volumio volumio[6270]: LoungeScreenDisconnected { Apr 07 04:07:03 volumio volumio[6270]: AID: null, Apr 07 04:07:03 volumio volumio[6270]: name: 'loungeScreenDisconnected', Apr 07 04:07:03 volumio volumio[6270]: payload: {} Apr 07 04:07:03 volumio volumio[6270]: } Apr 07 04:07:03 volumio volumio[6270]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:03 volumio volumio[6270]: Stack trace: Apr 07 04:07:03 volumio volumio[6270]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:03 volumio volumio[6270]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:03 volumio volumio[6270]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:03 volumio volumio[6270]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:03 volumio volumio[6270]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:03 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:03 volumio volumio[6270]: error: [ytcr] Failed to start plugin: Apr 07 04:07:03 volumio volumio[6270]: (AppError) Failed to start YouTubeApp Apr 07 04:07:03 volumio volumio[6270]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:03 volumio volumio[6270]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:03 volumio volumio[6270]: Error info: { Apr 07 04:07:03 volumio volumio[6270]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:03 volumio volumio[6270]: } Apr 07 04:07:03 volumio volumio[6270]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:03 volumio volumio[6270]: Stack trace: Apr 07 04:07:03 volumio volumio[6270]: AppError: Failed to start YouTubeApp Apr 07 04:07:03 volumio volumio[6270]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:03 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:03 volumio volumio[6270]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:03 volumio volumio[6270]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:03 volumio volumio[6270]: LoungeScreenDisconnected { Apr 07 04:07:03 volumio volumio[6270]: AID: null, Apr 07 04:07:03 volumio volumio[6270]: name: 'loungeScreenDisconnected', Apr 07 04:07:03 volumio volumio[6270]: payload: {} Apr 07 04:07:03 volumio volumio[6270]: } Apr 07 04:07:03 volumio volumio[6270]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:03 volumio volumio[6270]: Stack trace: Apr 07 04:07:03 volumio volumio[6270]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:03 volumio volumio[6270]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:03 volumio volumio[6270]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:03 volumio volumio[6270]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:03 volumio volumio[6270]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:03 volumio volumio[6270]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:03 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:03 volumio volumio[6270]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 07 04:07:03 volumio volumio[6270]: info: CoreCommandRouter::volumioGetState Apr 07 04:07:03 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:03 volumio volumio[6270]: info: CoreStateMachine::pushState Apr 07 04:07:03 volumio volumio[6270]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:03 volumio volumio[6270]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:03 volumio volumio[6270]: info: [squeezelite_mc] Server discovery started Apr 07 04:07:03 volumio volumio[6270]: info: [squeezelite_mc] Player finder started Apr 07 04:07:03 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:03 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:03 volumio volumio[6270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:03 volumio volumio[6270]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 07 04:07:03 volumio volumio[6270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:03 volumio volumio[6270]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 07 04:07:03 volumio volumio[6270]: at doSend (dgram.js:692:16) Apr 07 04:07:03 volumio volumio[6270]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 07 04:07:03 volumio volumio[6270]: at afterDns (dgram.js:638:5) Apr 07 04:07:03 volumio volumio[6270]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 07 04:07:03 volumio volumio[6270]: errno: -101, Apr 07 04:07:03 volumio volumio[6270]: code: 'ENETUNREACH', Apr 07 04:07:03 volumio volumio[6270]: syscall: 'send', Apr 07 04:07:03 volumio volumio[6270]: address: '255.255.255.255', Apr 07 04:07:03 volumio volumio[6270]: port: 3483 Apr 07 04:07:03 volumio volumio[6270]: } Apr 07 04:07:03 volumio volumio[6270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:04 volumio mpd[6390]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:04 volumio sudo[6434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 04:06 Apr 07 04:07:04 volumio sudo[6434]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:04 volumio sudo[6434]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:04 volumio mpd[6390]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:04 volumio mpd[6390]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:04 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:04 volumio sudo[6374]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 07 04:07:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 07 04:07:04 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:04 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 07 04:07:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 31. Apr 07 04:07:04 volumio systemd[1]: Stopped Volumio Backend Module. Apr 07 04:07:04 volumio systemd[1]: Started Volumio Backend Module. Apr 07 04:07:04 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:04 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:05 volumio kernel: hwmon hwmon1: Voltage normalised Apr 07 04:07:06 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:06 volumio volumio[6449]: info: ------------------------------------------- Apr 07 04:07:06 volumio volumio[6449]: info: ----- Volumio3 ---- Apr 07 04:07:06 volumio volumio[6449]: info: ------------------------------------------- Apr 07 04:07:06 volumio volumio[6449]: info: ----- System startup ---- Apr 07 04:07:06 volumio volumio[6449]: info: ------------------------------------------- Apr 07 04:07:07 volumio volumio[6449]: info: MYVOLUMIO Environment detected Apr 07 04:07:07 volumio volumio[6449]: info: Plugin folders cleanup Apr 07 04:07:07 volumio volumio[6449]: info: Scanning into folder /volumio/app/plugins/ Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category audio_interface Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category miscellanea Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category music_service Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category plugins.json Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category system_controller Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category user_interface Apr 07 04:07:07 volumio volumio[6449]: info: Scanning into folder /data/plugins/ Apr 07 04:07:07 volumio volumio[6449]: info: Scanning category music_service Apr 07 04:07:07 volumio volumio[6449]: info: Plugin folders cleanup completed Apr 07 04:07:07 volumio volumio[6449]: info: ------------------------------------------- Apr 07 04:07:07 volumio volumio[6449]: info: ----- Core plugins startup ---- Apr 07 04:07:07 volumio volumio[6449]: info: ------------------------------------------- Apr 07 04:07:07 volumio volumio[6449]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 04:07:07 volumio volumio[6449]: info: Adding plugin upnp to MyMusic Plugins Apr 07 04:07:07 volumio volumio[6449]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 04:07:07 volumio volumio[6449]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 04:07:07 volumio volumio[6449]: info: Loading plugins from folder /data/plugins/ Apr 07 04:07:07 volumio volumio[6449]: info: Loading plugin "system"... Apr 07 04:07:07 volumio volumio[6449]: info: Loading plugin "appearance"... Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "network"... Apr 07 04:07:08 volumio volumio[6449]: info: Refreshing Cached IP Addresses Apr 07 04:07:08 volumio sudo[6474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 04:07:08 volumio sudo[6474]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:08 volumio sudo[6474]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:08 volumio sudo[6476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 04:07:08 volumio sudo[6476]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "services"... Apr 07 04:07:08 volumio sudo[6476]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "alsa_controller"... Apr 07 04:07:08 volumio sudo[6479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 07 04:07:08 volumio sudo[6479]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:08 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "wizard"... Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "volumio_command_line_client"... Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "upnp"... Apr 07 04:07:08 volumio volumio[6449]: info: [1712452028774] Starting Upmpd Daemon Apr 07 04:07:08 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "my_music"... Apr 07 04:07:08 volumio volumio[6449]: info: Loading plugin "mpd"... Apr 07 04:07:09 volumio volumio[6449]: info: Creating MPD Configuration file Apr 07 04:07:09 volumio volumio-remote-updater[520]: [2024-04-07 04:07:09] [connect] Successful connection Apr 07 04:07:09 volumio volumio[6449]: info: Loading plugin "upnp_browser"... Apr 07 04:07:09 volumio sudo[6485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:09 volumio sudo[6485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:09 volumio sudo[6485]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:09 volumio sudo[6487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:09 volumio sudo[6487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:09 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:09 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:09 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:09 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:09 volumio sudo[6479]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:09 volumio volumio[6449]: info: Loading plugin "networkfs"... Apr 07 04:07:09 volumio volumio[6449]: info: Starting Udev Watcher for removable devices Apr 07 04:07:10 volumio volumio[6449]: info: Ignoring mount for partition: boot Apr 07 04:07:10 volumio volumio[6449]: info: Ignoring mount for partition: volumio Apr 07 04:07:10 volumio volumio[6449]: info: Ignoring mount for partition: volumio_data Apr 07 04:07:10 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "alarm-clock"... Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "airplay_emulation"... Apr 07 04:07:10 volumio volumio[6449]: info: Starting Shairport Sync Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "last_100"... Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "webradio"... Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "i2s_dacs"... Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "volumiodiscovery"... Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:10 volumio node[6449]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** For more information see Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:10 volumio volumio[6449]: *** WARNING *** For more information see Apr 07 04:07:10 volumio node[6449]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:10 volumio node[6449]: *** WARNING *** For more information see Apr 07 04:07:10 volumio node[6449]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:10 volumio node[6449]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:10 volumio node[6449]: *** WARNING *** For more information see Apr 07 04:07:10 volumio volumio[6449]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 04:07:10 volumio volumio[6449]: info: Discovery: Started advertising with name: Volumio Apr 07 04:07:10 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:10 volumio volumio[6449]: info: Loading plugin "squeezelite_mc"... Apr 07 04:07:10 volumio mpd[6492]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:11 volumio mpd[6492]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:11 volumio mpd[6492]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:11 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:11 volumio sudo[6487]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:11 volumio volumio[6449]: info: Loading plugin "youtube2"... Apr 07 04:07:12 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:12 volumio volumio[6449]: info: Loading plugin "ytcr"... Apr 07 04:07:14 volumio volumio[6449]: info: Loading plugin "outputs"... Apr 07 04:07:14 volumio volumio[6449]: info: Loading plugin "albumart"... Apr 07 04:07:14 volumio volumio[6449]: info: Plugin example_plugin is not enabled Apr 07 04:07:14 volumio volumio[6449]: info: Loading plugin "inputs"... Apr 07 04:07:14 volumio volumio[6449]: info: Loading plugin "updater_comm"... Apr 07 04:07:15 volumio volumio[6449]: info: Plugin mpdemulation is not enabled Apr 07 04:07:15 volumio volumio[6449]: info: Loading plugin "rest_api"... Apr 07 04:07:15 volumio volumio[6449]: info: Loading plugin "websocket"... Apr 07 04:07:15 volumio volumio[6449]: info: Plugin minidlna is not enabled Apr 07 04:07:15 volumio volumio[6449]: info: Loading i18n strings for locale ru Apr 07 04:07:15 volumio volumio[6449]: Updating browse sources language Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::initPlayerControls Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: Forking 3 albumart workers Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: Express server listening on port 3000 Apr 07 04:07:15 volumio volumio[6449]: [Metrics] WebUI: 9s 240.71ms Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::resetVolumioState Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::getcurrentVolume Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:15 volumio volumio[6449]: info: MPD Permissions set Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::pushState Apr 07 04:07:15 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::updateTrackBlock Apr 07 04:07:15 volumio volumio[6449]: info: CorePlayQueue::getTrackBlock Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:15 volumio volumio[6449]: info: MPD running with PID6492 Apr 07 04:07:15 volumio volumio[6449]: ,establishing connection Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:15 volumio volumio[6449]: error: updateQueue error: null Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::setRepeat null single undefined Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::pushState Apr 07 04:07:15 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::setRandom null Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::pushState Apr 07 04:07:15 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:15 volumio volumio[6449]: info: Setting Device type: Raspberry PI Apr 07 04:07:15 volumio volumio[6449]: info: Completed loading Core Plugins Apr 07 04:07:15 volumio volumio[6449]: info: Preparing to generate the ALSA configuration file Apr 07 04:07:15 volumio volumio[6449]: info: CoreStateMachine::pushState Apr 07 04:07:15 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:15 volumio volumio[6449]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:15 volumio volumio[6449]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 07 04:07:15 volumio volumio[6449]: info: Reading ALSA contributions from plugins. Apr 07 04:07:15 volumio volumio[6449]: error: updateQueue error: null Apr 07 04:07:15 volumio volumio[6449]: info: Asound.conf file unchanged, so no further update is needed Apr 07 04:07:15 volumio volumio[6449]: info: Output device has changed, restarting MPD Apr 07 04:07:16 volumio sudo[6554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:16 volumio sudo[6554]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:16 volumio volumio[6449]: info: Output device has changed, restarting Shairport Sync Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:16 volumio sudo[6554]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:16 volumio sudo[6556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:16 volumio sudo[6556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:16 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:16 volumio volumio[6449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:16 volumio volumio[6449]: info: ___________ START PLUGINS ___________ Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:16 volumio volumio[6449]: info: [1712452036202] CoreMusicLibrary::Adding element Media Servers Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:16 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:16 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:16 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:16 volumio volumio[6449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:16 volumio volumio[6449]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:16 volumio volumio[6449]: info: [1712452036387] CoreMusicLibrary::Adding element Last_100 Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:16 volumio volumio[6449]: info: [1712452036392] CoreMusicLibrary::Adding element Webradio Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:16 volumio volumio[6449]: info: [squeezelite_mc] Starting proxy server... Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:16 volumio volumio[6449]: info: [1712452036441] CoreMusicLibrary::Adding element YouTube2 Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:16 volumio volumio[6449]: Cannot find translation for source YouTube2 Apr 07 04:07:16 volumio volumio[6449]: info: Volumio Calling Home Apr 07 04:07:16 volumio volumio[6449]: Starting albumart workers Apr 07 04:07:16 volumio volumio[6449]: Starting albumart workers Apr 07 04:07:16 volumio volumio[6449]: info: [squeezelite_mc] Proxy server started on port 40109 Apr 07 04:07:16 volumio volumio[6449]: Starting albumart workers Apr 07 04:07:16 volumio volumio[6449]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:17 volumio volumio[6449]: Unhandled rejection Error: No sockets available, cannot start. Apr 07 04:07:17 volumio volumio[6449]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 07 04:07:17 volumio volumio[6449]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 07 04:07:17 volumio volumio[6449]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 07 04:07:17 volumio volumio[6449]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 07 04:07:17 volumio volumio[6449]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 07 04:07:17 volumio volumio[6449]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 07 04:07:17 volumio volumio[6449]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 07 04:07:17 volumio volumio[6449]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 07 04:07:17 volumio volumio[6449]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 07 04:07:17 volumio volumio[6449]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 07 04:07:17 volumio volumio[6449]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 07 04:07:17 volumio volumio[6449]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 07 04:07:17 volumio volumio[6449]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 07 04:07:17 volumio volumio[6449]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 07 04:07:17 volumio volumio[6449]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 07 04:07:17 volumio volumio[6449]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 07 04:07:17 volumio volumio[6449]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (8cit1duqm3qpvfigf7d80erhh5): Apr 07 04:07:17 volumio volumio[6449]: (ConnectionError) (YouTube) Connection error in getting lounge token Apr 07 04:07:17 volumio volumio[6449]: Error info: { Apr 07 04:07:17 volumio volumio[6449]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:17 volumio volumio[6449]: } Apr 07 04:07:17 volumio volumio[6449]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:17 volumio volumio[6449]: Stack trace: Apr 07 04:07:17 volumio volumio[6449]: ConnectionError: (YouTube) Connection error in getting lounge token Apr 07 04:07:17 volumio volumio[6449]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:17 volumio volumio[6449]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:17 volumio volumio[6449]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:17 volumio volumio[6449]: at async Promise.all (index 0) Apr 07 04:07:17 volumio volumio[6449]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:17 volumio volumio[6449]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:17 volumio volumio[6449]: Going to generate fresh screen Id and try again... Apr 07 04:07:17 volumio volumio[6449]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (j9au0cms8rtq71pg20otc3ea7i): Apr 07 04:07:17 volumio volumio[6449]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Apr 07 04:07:17 volumio volumio[6449]: Error info: { Apr 07 04:07:17 volumio volumio[6449]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:17 volumio volumio[6449]: } Apr 07 04:07:17 volumio volumio[6449]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:17 volumio volumio[6449]: Stack trace: Apr 07 04:07:17 volumio volumio[6449]: ConnectionError: (YouTube Music) Connection error in getting lounge token Apr 07 04:07:17 volumio volumio[6449]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:17 volumio volumio[6449]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:17 volumio volumio[6449]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:17 volumio volumio[6449]: at async Promise.all (index 1) Apr 07 04:07:17 volumio volumio[6449]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:17 volumio volumio[6449]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:17 volumio volumio[6449]: Going to generate fresh screen Id and try again... Apr 07 04:07:17 volumio volumio[6449]: info: CoreCommandRouter::volumioGetState Apr 07 04:07:17 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:17 volumio volumio[6449]: info: CoreStateMachine::pushState Apr 07 04:07:17 volumio volumio[6449]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:17 volumio volumio[6449]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:17 volumio volumio[6449]: info: [squeezelite_mc] Server discovery started Apr 07 04:07:17 volumio volumio[6449]: info: [squeezelite_mc] Player finder started Apr 07 04:07:17 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:17 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:17 volumio volumio[6449]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:17 volumio volumio[6449]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 07 04:07:17 volumio volumio[6449]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:17 volumio volumio[6449]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 07 04:07:17 volumio volumio[6449]: at doSend (dgram.js:692:16) Apr 07 04:07:17 volumio volumio[6449]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 07 04:07:17 volumio volumio[6449]: at afterDns (dgram.js:638:5) Apr 07 04:07:17 volumio volumio[6449]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 07 04:07:17 volumio volumio[6449]: errno: -101, Apr 07 04:07:17 volumio volumio[6449]: code: 'ENETUNREACH', Apr 07 04:07:17 volumio volumio[6449]: syscall: 'send', Apr 07 04:07:17 volumio volumio[6449]: address: '255.255.255.255', Apr 07 04:07:17 volumio volumio[6449]: port: 3483 Apr 07 04:07:17 volumio volumio[6449]: } Apr 07 04:07:17 volumio volumio[6449]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:17 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 07 04:07:18 volumio sudo[6615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 04:06 Apr 07 04:07:18 volumio sudo[6615]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:19 volumio mpd[6572]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:19 volumio sudo[6615]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 07 04:07:19 volumio sudo[6556]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 07 04:07:19 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:19 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 07 04:07:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 32. Apr 07 04:07:19 volumio systemd[1]: Stopped Volumio Backend Module. Apr 07 04:07:19 volumio systemd[1]: Started Volumio Backend Module. Apr 07 04:07:19 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:19 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:19 volumio mpd[6572]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:19 volumio mpd[6572]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:19 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:21 volumio volumio[6628]: info: ------------------------------------------- Apr 07 04:07:21 volumio volumio[6628]: info: ----- Volumio3 ---- Apr 07 04:07:21 volumio volumio[6628]: info: ------------------------------------------- Apr 07 04:07:21 volumio volumio[6628]: info: ----- System startup ---- Apr 07 04:07:21 volumio volumio[6628]: info: ------------------------------------------- Apr 07 04:07:22 volumio kernel: hwmon hwmon1: Voltage normalised Apr 07 04:07:22 volumio volumio[6628]: info: MYVOLUMIO Environment detected Apr 07 04:07:22 volumio volumio[6628]: info: Plugin folders cleanup Apr 07 04:07:22 volumio volumio[6628]: info: Scanning into folder /volumio/app/plugins/ Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category audio_interface Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category miscellanea Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category music_service Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category plugins.json Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category system_controller Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category user_interface Apr 07 04:07:22 volumio volumio[6628]: info: Scanning into folder /data/plugins/ Apr 07 04:07:22 volumio volumio[6628]: info: Scanning category music_service Apr 07 04:07:22 volumio volumio[6628]: info: Plugin folders cleanup completed Apr 07 04:07:22 volumio volumio[6628]: info: ------------------------------------------- Apr 07 04:07:22 volumio volumio[6628]: info: ----- Core plugins startup ---- Apr 07 04:07:22 volumio volumio[6628]: info: ------------------------------------------- Apr 07 04:07:22 volumio volumio[6628]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 04:07:22 volumio volumio[6628]: info: Adding plugin upnp to MyMusic Plugins Apr 07 04:07:22 volumio volumio[6628]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 04:07:22 volumio volumio[6628]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 04:07:22 volumio volumio[6628]: info: Loading plugins from folder /data/plugins/ Apr 07 04:07:22 volumio volumio[6628]: info: Loading plugin "system"... Apr 07 04:07:22 volumio volumio[6628]: info: Loading plugin "appearance"... Apr 07 04:07:23 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "network"... Apr 07 04:07:23 volumio volumio[6628]: info: Refreshing Cached IP Addresses Apr 07 04:07:23 volumio sudo[6655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 04:07:23 volumio sudo[6655]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:23 volumio sudo[6655]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "services"... Apr 07 04:07:23 volumio sudo[6657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 04:07:23 volumio sudo[6657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "alsa_controller"... Apr 07 04:07:23 volumio sudo[6657]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:23 volumio sudo[6660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 07 04:07:23 volumio sudo[6660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:23 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "wizard"... Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "volumio_command_line_client"... Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "upnp"... Apr 07 04:07:23 volumio volumio[6628]: info: [1712452043861] Starting Upmpd Daemon Apr 07 04:07:23 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "my_music"... Apr 07 04:07:23 volumio volumio[6628]: info: Loading plugin "mpd"... Apr 07 04:07:24 volumio volumio-remote-updater[520]: [2024-04-07 04:07:24] [connect] Successful connection Apr 07 04:07:24 volumio volumio[6628]: info: Creating MPD Configuration file Apr 07 04:07:24 volumio volumio[6628]: info: Loading plugin "upnp_browser"... Apr 07 04:07:24 volumio sudo[6666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:24 volumio sudo[6666]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:24 volumio sudo[6666]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:24 volumio sudo[6668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:24 volumio sudo[6668]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:24 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:24 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:24 volumio sudo[6660]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:24 volumio volumio[6628]: info: Loading plugin "networkfs"... Apr 07 04:07:24 volumio volumio[6628]: info: Starting Udev Watcher for removable devices Apr 07 04:07:25 volumio volumio[6628]: info: Ignoring mount for partition: boot Apr 07 04:07:25 volumio volumio[6628]: info: Ignoring mount for partition: volumio Apr 07 04:07:25 volumio volumio[6628]: info: Ignoring mount for partition: volumio_data Apr 07 04:07:25 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "alarm-clock"... Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "airplay_emulation"... Apr 07 04:07:25 volumio volumio[6628]: info: Starting Shairport Sync Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "last_100"... Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "webradio"... Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "i2s_dacs"... Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "volumiodiscovery"... Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** For more information see Apr 07 04:07:25 volumio node[6628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:25 volumio volumio[6628]: *** WARNING *** For more information see Apr 07 04:07:25 volumio node[6628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:25 volumio node[6628]: *** WARNING *** For more information see Apr 07 04:07:25 volumio node[6628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:25 volumio node[6628]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:25 volumio node[6628]: *** WARNING *** For more information see Apr 07 04:07:25 volumio volumio[6628]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 04:07:25 volumio volumio[6628]: info: Discovery: Started advertising with name: Volumio Apr 07 04:07:25 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:25 volumio volumio[6628]: info: Loading plugin "squeezelite_mc"... Apr 07 04:07:25 volumio mpd[6673]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:26 volumio mpd[6673]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:26 volumio mpd[6673]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:26 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:26 volumio sudo[6668]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:26 volumio volumio[6628]: info: Loading plugin "youtube2"... Apr 07 04:07:27 volumio volumio[6628]: info: Loading plugin "ytcr"... Apr 07 04:07:28 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:29 volumio volumio[6628]: info: Loading plugin "outputs"... Apr 07 04:07:29 volumio volumio[6628]: info: Loading plugin "albumart"... Apr 07 04:07:30 volumio volumio[6628]: info: Plugin example_plugin is not enabled Apr 07 04:07:30 volumio volumio[6628]: info: Loading plugin "inputs"... Apr 07 04:07:30 volumio volumio[6628]: info: Loading plugin "updater_comm"... Apr 07 04:07:30 volumio volumio[6628]: info: Plugin mpdemulation is not enabled Apr 07 04:07:30 volumio volumio[6628]: info: Loading plugin "rest_api"... Apr 07 04:07:30 volumio volumio[6628]: info: Loading plugin "websocket"... Apr 07 04:07:30 volumio volumio[6628]: info: Plugin minidlna is not enabled Apr 07 04:07:30 volumio volumio[6628]: info: Loading i18n strings for locale ru Apr 07 04:07:30 volumio volumio[6628]: Updating browse sources language Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::initPlayerControls Apr 07 04:07:30 volumio volumio[6628]: Forking 3 albumart workers Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: Express server listening on port 3000 Apr 07 04:07:30 volumio volumio[6628]: [Metrics] WebUI: 9s 168.36ms Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::resetVolumioState Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::getcurrentVolume Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:30 volumio volumio[6628]: info: MPD Permissions set Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::pushState Apr 07 04:07:30 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::updateTrackBlock Apr 07 04:07:30 volumio volumio[6628]: info: CorePlayQueue::getTrackBlock Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:30 volumio volumio[6628]: info: MPD running with PID6673 Apr 07 04:07:30 volumio volumio[6628]: ,establishing connection Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:30 volumio volumio[6628]: error: updateQueue error: null Apr 07 04:07:30 volumio volumio[6628]: info: Setting Device type: Raspberry PI Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::setRepeat null single undefined Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::pushState Apr 07 04:07:30 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::setRandom null Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::pushState Apr 07 04:07:30 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:30 volumio volumio[6628]: info: Completed loading Core Plugins Apr 07 04:07:30 volumio volumio[6628]: info: Preparing to generate the ALSA configuration file Apr 07 04:07:30 volumio volumio[6628]: info: CoreStateMachine::pushState Apr 07 04:07:30 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:30 volumio volumio[6628]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:30 volumio volumio[6628]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 07 04:07:30 volumio volumio[6628]: info: Reading ALSA contributions from plugins. Apr 07 04:07:30 volumio volumio[6628]: error: updateQueue error: null Apr 07 04:07:31 volumio volumio[6628]: info: Asound.conf file unchanged, so no further update is needed Apr 07 04:07:31 volumio volumio[6628]: info: Output device has changed, restarting MPD Apr 07 04:07:31 volumio sudo[6735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:31 volumio sudo[6735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:31 volumio volumio[6628]: info: Output device has changed, restarting Shairport Sync Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:31 volumio sudo[6735]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:31 volumio sudo[6737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:31 volumio sudo[6737]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:31 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:31 volumio volumio[6628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:31 volumio volumio[6628]: info: ___________ START PLUGINS ___________ Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:31 volumio volumio[6628]: info: [1712452051201] CoreMusicLibrary::Adding element Media Servers Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:31 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:31 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:31 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:31 volumio volumio[6628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:31 volumio volumio[6628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:31 volumio volumio[6628]: info: [1712452051389] CoreMusicLibrary::Adding element Last_100 Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:31 volumio volumio[6628]: info: [1712452051395] CoreMusicLibrary::Adding element Webradio Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:31 volumio volumio[6628]: info: [squeezelite_mc] Starting proxy server... Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:31 volumio volumio[6628]: info: [1712452051456] CoreMusicLibrary::Adding element YouTube2 Apr 07 04:07:31 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:31 volumio volumio[6628]: Cannot find translation for source YouTube2 Apr 07 04:07:31 volumio volumio[6628]: Starting albumart workers Apr 07 04:07:31 volumio volumio[6628]: info: Volumio Calling Home Apr 07 04:07:31 volumio volumio[6628]: Starting albumart workers Apr 07 04:07:31 volumio volumio[6628]: Starting albumart workers Apr 07 04:07:31 volumio volumio[6628]: info: [squeezelite_mc] Proxy server started on port 39529 Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:32 volumio volumio[6628]: Unhandled rejection Error: No sockets available, cannot start. Apr 07 04:07:32 volumio volumio[6628]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 07 04:07:32 volumio volumio[6628]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 07 04:07:32 volumio volumio[6628]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 07 04:07:32 volumio volumio[6628]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 07 04:07:32 volumio volumio[6628]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 07 04:07:32 volumio volumio[6628]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 07 04:07:32 volumio volumio[6628]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 07 04:07:32 volumio volumio[6628]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 07 04:07:32 volumio volumio[6628]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 07 04:07:32 volumio volumio[6628]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 07 04:07:32 volumio volumio[6628]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 07 04:07:32 volumio volumio[6628]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 07 04:07:32 volumio volumio[6628]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 07 04:07:32 volumio volumio[6628]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 07 04:07:32 volumio volumio[6628]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 07 04:07:32 volumio volumio[6628]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (8cit1duqm3qpvfigf7d80erhh5): Apr 07 04:07:32 volumio volumio[6628]: (ConnectionError) (YouTube) Connection error in getting lounge token Apr 07 04:07:32 volumio volumio[6628]: Error info: { Apr 07 04:07:32 volumio volumio[6628]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: ConnectionError: (YouTube) Connection error in getting lounge token Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:32 volumio volumio[6628]: at async Promise.all (index 0) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: Going to generate fresh screen Id and try again... Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (j9au0cms8rtq71pg20otc3ea7i): Apr 07 04:07:32 volumio volumio[6628]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Apr 07 04:07:32 volumio volumio[6628]: Error info: { Apr 07 04:07:32 volumio volumio[6628]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: ConnectionError: (YouTube Music) Connection error in getting lounge token Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:32 volumio volumio[6628]: at async Promise.all (index 1) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: Going to generate fresh screen Id and try again... Apr 07 04:07:32 volumio volumio[6628]: info: MPD Permissions set Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:32 volumio volumio[6628]: LoungeScreenDisconnected { Apr 07 04:07:32 volumio volumio[6628]: AID: null, Apr 07 04:07:32 volumio volumio[6628]: name: 'loungeScreenDisconnected', Apr 07 04:07:32 volumio volumio[6628]: payload: {} Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:32 volumio volumio[6628]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:32 volumio volumio[6628]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:32 volumio volumio[6628]: at new Promise () Apr 07 04:07:32 volumio volumio[6628]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:32 volumio volumio[6628]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:32 volumio volumio[6628]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async Promise.all (index 0) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] Failed to start receiver: Apr 07 04:07:32 volumio volumio[6628]: (AppError) Failed to start YouTubeApp Apr 07 04:07:32 volumio volumio[6628]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:32 volumio volumio[6628]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:32 volumio volumio[6628]: Error info: { Apr 07 04:07:32 volumio volumio[6628]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: AppError: Failed to start YouTubeApp Apr 07 04:07:32 volumio volumio[6628]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:32 volumio volumio[6628]: LoungeScreenDisconnected { Apr 07 04:07:32 volumio volumio[6628]: AID: null, Apr 07 04:07:32 volumio volumio[6628]: name: 'loungeScreenDisconnected', Apr 07 04:07:32 volumio volumio[6628]: payload: {} Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:32 volumio volumio[6628]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:32 volumio volumio[6628]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:32 volumio volumio[6628]: at new Promise () Apr 07 04:07:32 volumio volumio[6628]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:32 volumio volumio[6628]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:32 volumio volumio[6628]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Apr 07 04:07:32 volumio volumio[6628]: at Array.forEach () Apr 07 04:07:32 volumio volumio[6628]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:32 volumio volumio[6628]: LoungeScreenDisconnected { Apr 07 04:07:32 volumio volumio[6628]: AID: null, Apr 07 04:07:32 volumio volumio[6628]: name: 'loungeScreenDisconnected', Apr 07 04:07:32 volumio volumio[6628]: payload: {} Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:32 volumio volumio[6628]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:32 volumio volumio[6628]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: error: [ytcr] Failed to start plugin: Apr 07 04:07:32 volumio volumio[6628]: (AppError) Failed to start YouTubeApp Apr 07 04:07:32 volumio volumio[6628]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:32 volumio volumio[6628]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:32 volumio volumio[6628]: Error info: { Apr 07 04:07:32 volumio volumio[6628]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: AppError: Failed to start YouTubeApp Apr 07 04:07:32 volumio volumio[6628]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:32 volumio volumio[6628]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:32 volumio volumio[6628]: LoungeScreenDisconnected { Apr 07 04:07:32 volumio volumio[6628]: AID: null, Apr 07 04:07:32 volumio volumio[6628]: name: 'loungeScreenDisconnected', Apr 07 04:07:32 volumio volumio[6628]: payload: {} Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:32 volumio volumio[6628]: Stack trace: Apr 07 04:07:32 volumio volumio[6628]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:32 volumio volumio[6628]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:32 volumio volumio[6628]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:32 volumio volumio[6628]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:32 volumio volumio[6628]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:32 volumio volumio[6628]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:32 volumio volumio[6628]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::volumioGetState Apr 07 04:07:32 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:32 volumio volumio[6628]: info: CoreStateMachine::pushState Apr 07 04:07:32 volumio volumio[6628]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:32 volumio volumio[6628]: info: [squeezelite_mc] Server discovery started Apr 07 04:07:32 volumio volumio[6628]: info: [squeezelite_mc] Player finder started Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:32 volumio volumio[6628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:32 volumio volumio[6628]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 07 04:07:32 volumio volumio[6628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:32 volumio volumio[6628]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 07 04:07:32 volumio volumio[6628]: at doSend (dgram.js:692:16) Apr 07 04:07:32 volumio volumio[6628]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 07 04:07:32 volumio volumio[6628]: at afterDns (dgram.js:638:5) Apr 07 04:07:32 volumio volumio[6628]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 07 04:07:32 volumio volumio[6628]: errno: -101, Apr 07 04:07:32 volumio volumio[6628]: code: 'ENETUNREACH', Apr 07 04:07:32 volumio volumio[6628]: syscall: 'send', Apr 07 04:07:32 volumio volumio[6628]: address: '255.255.255.255', Apr 07 04:07:32 volumio volumio[6628]: port: 3483 Apr 07 04:07:32 volumio volumio[6628]: } Apr 07 04:07:32 volumio volumio[6628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:33 volumio sudo[6795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 04:06 Apr 07 04:07:33 volumio sudo[6795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:33 volumio mpd[6750]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:33 volumio sudo[6795]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 07 04:07:33 volumio sudo[6737]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 07 04:07:33 volumio mpd[6750]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:33 volumio mpd[6750]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:33 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:33 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:33 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 07 04:07:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 33. Apr 07 04:07:33 volumio systemd[1]: Stopped Volumio Backend Module. Apr 07 04:07:33 volumio systemd[1]: Started Volumio Backend Module. Apr 07 04:07:33 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:33 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:34 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:35 volumio volumio[6810]: info: ------------------------------------------- Apr 07 04:07:35 volumio volumio[6810]: info: ----- Volumio3 ---- Apr 07 04:07:35 volumio volumio[6810]: info: ------------------------------------------- Apr 07 04:07:35 volumio volumio[6810]: info: ----- System startup ---- Apr 07 04:07:35 volumio volumio[6810]: info: ------------------------------------------- Apr 07 04:07:36 volumio volumio[6810]: info: MYVOLUMIO Environment detected Apr 07 04:07:36 volumio volumio[6810]: info: Plugin folders cleanup Apr 07 04:07:36 volumio volumio[6810]: info: Scanning into folder /volumio/app/plugins/ Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category audio_interface Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category miscellanea Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category music_service Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category plugins.json Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category system_controller Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category user_interface Apr 07 04:07:36 volumio volumio[6810]: info: Scanning into folder /data/plugins/ Apr 07 04:07:36 volumio volumio[6810]: info: Scanning category music_service Apr 07 04:07:36 volumio volumio[6810]: info: Plugin folders cleanup completed Apr 07 04:07:36 volumio volumio[6810]: info: ------------------------------------------- Apr 07 04:07:36 volumio volumio[6810]: info: ----- Core plugins startup ---- Apr 07 04:07:36 volumio volumio[6810]: info: ------------------------------------------- Apr 07 04:07:36 volumio volumio[6810]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 04:07:36 volumio volumio[6810]: info: Adding plugin upnp to MyMusic Plugins Apr 07 04:07:36 volumio volumio[6810]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 04:07:36 volumio volumio[6810]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 04:07:36 volumio volumio[6810]: info: Loading plugins from folder /data/plugins/ Apr 07 04:07:36 volumio volumio[6810]: info: Loading plugin "system"... Apr 07 04:07:36 volumio volumio[6810]: info: Loading plugin "appearance"... Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "network"... Apr 07 04:07:37 volumio volumio[6810]: info: Refreshing Cached IP Addresses Apr 07 04:07:37 volumio sudo[6835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 04:07:37 volumio sudo[6835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:37 volumio sudo[6835]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:37 volumio sudo[6837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 04:07:37 volumio sudo[6837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "services"... Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "alsa_controller"... Apr 07 04:07:37 volumio sudo[6837]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:37 volumio sudo[6840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 07 04:07:37 volumio sudo[6840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:37 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "wizard"... Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "volumio_command_line_client"... Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "upnp"... Apr 07 04:07:37 volumio volumio[6810]: info: [1712452057744] Starting Upmpd Daemon Apr 07 04:07:37 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "my_music"... Apr 07 04:07:37 volumio volumio[6810]: info: Loading plugin "mpd"... Apr 07 04:07:38 volumio volumio[6810]: info: Creating MPD Configuration file Apr 07 04:07:38 volumio volumio[6810]: info: Loading plugin "upnp_browser"... Apr 07 04:07:38 volumio sudo[6846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:38 volumio sudo[6846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:38 volumio sudo[6846]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:38 volumio sudo[6848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:38 volumio sudo[6848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:38 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:38 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:38 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:38 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:38 volumio sudo[6840]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:38 volumio volumio[6810]: info: Loading plugin "networkfs"... Apr 07 04:07:38 volumio volumio[6810]: info: Starting Udev Watcher for removable devices Apr 07 04:07:39 volumio volumio[6810]: info: Ignoring mount for partition: boot Apr 07 04:07:39 volumio volumio[6810]: info: Ignoring mount for partition: volumio Apr 07 04:07:39 volumio volumio[6810]: info: Ignoring mount for partition: volumio_data Apr 07 04:07:39 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "alarm-clock"... Apr 07 04:07:39 volumio volumio-remote-updater[520]: [2024-04-07 04:07:39] [connect] Successful connection Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "airplay_emulation"... Apr 07 04:07:39 volumio volumio[6810]: info: Starting Shairport Sync Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "last_100"... Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "webradio"... Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "i2s_dacs"... Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "volumiodiscovery"... Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** For more information see Apr 07 04:07:39 volumio node[6810]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:39 volumio volumio[6810]: *** WARNING *** For more information see Apr 07 04:07:39 volumio node[6810]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:39 volumio node[6810]: *** WARNING *** For more information see Apr 07 04:07:39 volumio node[6810]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:39 volumio node[6810]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:39 volumio node[6810]: *** WARNING *** For more information see Apr 07 04:07:39 volumio volumio[6810]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 04:07:39 volumio volumio[6810]: info: Discovery: Started advertising with name: Volumio Apr 07 04:07:39 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:39 volumio volumio[6810]: info: Loading plugin "squeezelite_mc"... Apr 07 04:07:39 volumio mpd[6853]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:40 volumio mpd[6853]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:40 volumio mpd[6853]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:40 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:40 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:40 volumio sudo[6848]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:40 volumio volumio[6810]: info: Loading plugin "youtube2"... Apr 07 04:07:41 volumio volumio[6810]: info: Loading plugin "ytcr"... Apr 07 04:07:43 volumio volumio[6810]: info: Loading plugin "outputs"... Apr 07 04:07:43 volumio volumio[6810]: info: Loading plugin "albumart"... Apr 07 04:07:43 volumio volumio[6810]: info: Plugin example_plugin is not enabled Apr 07 04:07:43 volumio volumio[6810]: info: Loading plugin "inputs"... Apr 07 04:07:43 volumio volumio[6810]: info: Loading plugin "updater_comm"... Apr 07 04:07:44 volumio volumio[6810]: info: Plugin mpdemulation is not enabled Apr 07 04:07:44 volumio volumio[6810]: info: Loading plugin "rest_api"... Apr 07 04:07:44 volumio volumio[6810]: info: Loading plugin "websocket"... Apr 07 04:07:44 volumio volumio[6810]: info: Plugin minidlna is not enabled Apr 07 04:07:44 volumio volumio[6810]: info: Loading i18n strings for locale ru Apr 07 04:07:44 volumio volumio[6810]: Updating browse sources language Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: Forking 3 albumart workers Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::initPlayerControls Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:44 volumio volumio[6810]: Express server listening on port 3000 Apr 07 04:07:44 volumio volumio[6810]: [Metrics] WebUI: 9s 516.67ms Apr 07 04:07:44 volumio volumio[6810]: info: CoreStateMachine::resetVolumioState Apr 07 04:07:44 volumio volumio[6810]: info: CoreStateMachine::getcurrentVolume Apr 07 04:07:44 volumio volumio[6810]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:44 volumio volumio[6810]: info: MPD Permissions set Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::pushState Apr 07 04:07:45 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::updateTrackBlock Apr 07 04:07:45 volumio volumio[6810]: info: CorePlayQueue::getTrackBlock Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:45 volumio volumio[6810]: info: MPD running with PID6853 Apr 07 04:07:45 volumio volumio[6810]: ,establishing connection Apr 07 04:07:45 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: error: updateQueue error: null Apr 07 04:07:45 volumio volumio[6810]: info: Setting Device type: Raspberry PI Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::setRepeat null single undefined Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::pushState Apr 07 04:07:45 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::setRandom null Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::pushState Apr 07 04:07:45 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:45 volumio volumio[6810]: info: Completed loading Core Plugins Apr 07 04:07:45 volumio volumio[6810]: info: Preparing to generate the ALSA configuration file Apr 07 04:07:45 volumio volumio[6810]: info: CoreStateMachine::pushState Apr 07 04:07:45 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:45 volumio volumio[6810]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 07 04:07:45 volumio volumio[6810]: info: Reading ALSA contributions from plugins. Apr 07 04:07:45 volumio volumio[6810]: error: updateQueue error: null Apr 07 04:07:45 volumio volumio[6810]: info: Asound.conf file unchanged, so no further update is needed Apr 07 04:07:45 volumio volumio[6810]: info: Output device has changed, restarting MPD Apr 07 04:07:45 volumio volumio[6810]: info: Output device has changed, restarting Shairport Sync Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:45 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:45 volumio sudo[6915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:45 volumio sudo[6917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:45 volumio sudo[6917]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:45 volumio sudo[6915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:45 volumio sudo[6915]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:45 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:45 volumio volumio[6810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:45 volumio volumio[6810]: info: ___________ START PLUGINS ___________ Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:45 volumio volumio[6810]: info: [1712452065945] CoreMusicLibrary::Adding element Media Servers Apr 07 04:07:45 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:45 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:45 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:46 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:46 volumio volumio[6810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:07:46 volumio volumio[6810]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:46 volumio volumio[6810]: info: [1712452066270] CoreMusicLibrary::Adding element Last_100 Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:46 volumio volumio[6810]: info: [1712452066280] CoreMusicLibrary::Adding element Webradio Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:46 volumio volumio[6810]: info: [squeezelite_mc] Starting proxy server... Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:07:46 volumio volumio[6810]: info: [1712452066372] CoreMusicLibrary::Adding element YouTube2 Apr 07 04:07:46 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:07:46 volumio volumio[6810]: Cannot find translation for source YouTube2 Apr 07 04:07:46 volumio volumio[6810]: info: Volumio Calling Home Apr 07 04:07:46 volumio volumio[6810]: Starting albumart workers Apr 07 04:07:46 volumio volumio[6810]: info: [squeezelite_mc] Proxy server started on port 39075 Apr 07 04:07:46 volumio volumio[6810]: Starting albumart workers Apr 07 04:07:46 volumio volumio[6810]: Starting albumart workers Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:07:47 volumio volumio[6810]: Unhandled rejection Error: No sockets available, cannot start. Apr 07 04:07:47 volumio volumio[6810]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 07 04:07:47 volumio volumio[6810]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 07 04:07:47 volumio volumio[6810]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 07 04:07:47 volumio volumio[6810]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 07 04:07:47 volumio volumio[6810]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 07 04:07:47 volumio volumio[6810]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 07 04:07:47 volumio volumio[6810]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 07 04:07:47 volumio volumio[6810]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 07 04:07:47 volumio volumio[6810]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 07 04:07:47 volumio volumio[6810]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 07 04:07:47 volumio volumio[6810]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 07 04:07:47 volumio volumio[6810]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 07 04:07:47 volumio volumio[6810]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 07 04:07:47 volumio volumio[6810]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 07 04:07:47 volumio volumio[6810]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 07 04:07:47 volumio volumio[6810]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (8cit1duqm3qpvfigf7d80erhh5): Apr 07 04:07:47 volumio volumio[6810]: (ConnectionError) (YouTube) Connection error in getting lounge token Apr 07 04:07:47 volumio volumio[6810]: Error info: { Apr 07 04:07:47 volumio volumio[6810]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: ConnectionError: (YouTube) Connection error in getting lounge token Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:47 volumio volumio[6810]: at async Promise.all (index 0) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: Going to generate fresh screen Id and try again... Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (j9au0cms8rtq71pg20otc3ea7i): Apr 07 04:07:47 volumio volumio[6810]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Apr 07 04:07:47 volumio volumio[6810]: Error info: { Apr 07 04:07:47 volumio volumio[6810]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: ConnectionError: (YouTube Music) Connection error in getting lounge token Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:07:47 volumio volumio[6810]: at async Promise.all (index 1) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: Going to generate fresh screen Id and try again... Apr 07 04:07:47 volumio volumio[6810]: info: MPD Permissions set Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:47 volumio volumio[6810]: LoungeScreenDisconnected { Apr 07 04:07:47 volumio volumio[6810]: AID: null, Apr 07 04:07:47 volumio volumio[6810]: name: 'loungeScreenDisconnected', Apr 07 04:07:47 volumio volumio[6810]: payload: {} Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:47 volumio volumio[6810]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:47 volumio volumio[6810]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:47 volumio volumio[6810]: at new Promise () Apr 07 04:07:47 volumio volumio[6810]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:47 volumio volumio[6810]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:47 volumio volumio[6810]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async Promise.all (index 0) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] Failed to start receiver: Apr 07 04:07:47 volumio volumio[6810]: (AppError) Failed to start YouTubeApp Apr 07 04:07:47 volumio volumio[6810]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:47 volumio volumio[6810]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:47 volumio volumio[6810]: Error info: { Apr 07 04:07:47 volumio volumio[6810]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: AppError: Failed to start YouTubeApp Apr 07 04:07:47 volumio volumio[6810]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:47 volumio volumio[6810]: LoungeScreenDisconnected { Apr 07 04:07:47 volumio volumio[6810]: AID: null, Apr 07 04:07:47 volumio volumio[6810]: name: 'loungeScreenDisconnected', Apr 07 04:07:47 volumio volumio[6810]: payload: {} Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:47 volumio volumio[6810]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:07:47 volumio volumio[6810]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:07:47 volumio volumio[6810]: at new Promise () Apr 07 04:07:47 volumio volumio[6810]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:07:47 volumio volumio[6810]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:07:47 volumio volumio[6810]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Apr 07 04:07:47 volumio volumio[6810]: at Array.forEach () Apr 07 04:07:47 volumio volumio[6810]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:07:47 volumio volumio[6810]: LoungeScreenDisconnected { Apr 07 04:07:47 volumio volumio[6810]: AID: null, Apr 07 04:07:47 volumio volumio[6810]: name: 'loungeScreenDisconnected', Apr 07 04:07:47 volumio volumio[6810]: payload: {} Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:47 volumio volumio[6810]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:47 volumio volumio[6810]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: error: [ytcr] Failed to start plugin: Apr 07 04:07:47 volumio volumio[6810]: (AppError) Failed to start YouTubeApp Apr 07 04:07:47 volumio volumio[6810]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:07:47 volumio volumio[6810]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:07:47 volumio volumio[6810]: Error info: { Apr 07 04:07:47 volumio volumio[6810]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: AppError: Failed to start YouTubeApp Apr 07 04:07:47 volumio volumio[6810]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:07:47 volumio volumio[6810]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:07:47 volumio volumio[6810]: LoungeScreenDisconnected { Apr 07 04:07:47 volumio volumio[6810]: AID: null, Apr 07 04:07:47 volumio volumio[6810]: name: 'loungeScreenDisconnected', Apr 07 04:07:47 volumio volumio[6810]: payload: {} Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:07:47 volumio volumio[6810]: Stack trace: Apr 07 04:07:47 volumio volumio[6810]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:07:47 volumio volumio[6810]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:07:47 volumio volumio[6810]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:07:47 volumio volumio[6810]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:07:47 volumio volumio[6810]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:07:47 volumio volumio[6810]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:07:47 volumio volumio[6810]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::volumioGetState Apr 07 04:07:47 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:47 volumio volumio[6810]: info: CoreStateMachine::pushState Apr 07 04:07:47 volumio volumio[6810]: info: CorePlayQueue::getTrack 0 Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::volumioPushState Apr 07 04:07:47 volumio volumio[6810]: info: [squeezelite_mc] Server discovery started Apr 07 04:07:47 volumio volumio[6810]: info: [squeezelite_mc] Player finder started Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:47 volumio volumio[6810]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:07:47 volumio volumio[6810]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 07 04:07:47 volumio volumio[6810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:47 volumio volumio[6810]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 07 04:07:47 volumio volumio[6810]: at doSend (dgram.js:692:16) Apr 07 04:07:47 volumio volumio[6810]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 07 04:07:47 volumio volumio[6810]: at afterDns (dgram.js:638:5) Apr 07 04:07:47 volumio volumio[6810]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 07 04:07:47 volumio volumio[6810]: errno: -101, Apr 07 04:07:47 volumio volumio[6810]: code: 'ENETUNREACH', Apr 07 04:07:47 volumio volumio[6810]: syscall: 'send', Apr 07 04:07:47 volumio volumio[6810]: address: '255.255.255.255', Apr 07 04:07:47 volumio volumio[6810]: port: 3483 Apr 07 04:07:47 volumio volumio[6810]: } Apr 07 04:07:47 volumio volumio[6810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:07:49 volumio mpd[6933]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:49 volumio sudo[6977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 04:06 Apr 07 04:07:49 volumio sudo[6977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:49 volumio sudo[6977]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:50 volumio mpd[6933]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:50 volumio mpd[6933]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:50 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:50 volumio sudo[6917]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 07 04:07:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 07 04:07:50 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 07 04:07:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. Apr 07 04:07:50 volumio systemd[1]: Stopped Volumio Backend Module. Apr 07 04:07:50 volumio systemd[1]: Started Volumio Backend Module. Apr 07 04:07:50 volumio systemd[1]: Started dynamicswap service. Apr 07 04:07:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 07 04:07:51 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:52 volumio volumio[6992]: info: ------------------------------------------- Apr 07 04:07:52 volumio volumio[6992]: info: ----- Volumio3 ---- Apr 07 04:07:52 volumio volumio[6992]: info: ------------------------------------------- Apr 07 04:07:52 volumio volumio[6992]: info: ----- System startup ---- Apr 07 04:07:52 volumio volumio[6992]: info: ------------------------------------------- Apr 07 04:07:53 volumio volumio[6992]: info: MYVOLUMIO Environment detected Apr 07 04:07:53 volumio volumio[6992]: info: Plugin folders cleanup Apr 07 04:07:53 volumio volumio[6992]: info: Scanning into folder /volumio/app/plugins/ Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category audio_interface Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category miscellanea Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category music_service Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category plugins.json Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category system_controller Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category user_interface Apr 07 04:07:53 volumio volumio[6992]: info: Scanning into folder /data/plugins/ Apr 07 04:07:53 volumio volumio[6992]: info: Scanning category music_service Apr 07 04:07:53 volumio volumio[6992]: info: Plugin folders cleanup completed Apr 07 04:07:53 volumio volumio[6992]: info: ------------------------------------------- Apr 07 04:07:53 volumio volumio[6992]: info: ----- Core plugins startup ---- Apr 07 04:07:53 volumio volumio[6992]: info: ------------------------------------------- Apr 07 04:07:53 volumio volumio[6992]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 04:07:53 volumio volumio[6992]: info: Adding plugin upnp to MyMusic Plugins Apr 07 04:07:53 volumio volumio[6992]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 04:07:53 volumio volumio[6992]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 04:07:53 volumio volumio[6992]: info: Loading plugins from folder /data/plugins/ Apr 07 04:07:53 volumio volumio[6992]: info: Loading plugin "system"... Apr 07 04:07:53 volumio volumio[6992]: info: Loading plugin "appearance"... Apr 07 04:07:54 volumio volumio-remote-updater[520]: [2024-04-07 04:07:54] [connect] Successful connection Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "network"... Apr 07 04:07:54 volumio volumio[6992]: info: Refreshing Cached IP Addresses Apr 07 04:07:54 volumio sudo[7016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 04:07:54 volumio sudo[7016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:54 volumio sudo[7016]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "services"... Apr 07 04:07:54 volumio sudo[7018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 04:07:54 volumio sudo[7018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "alsa_controller"... Apr 07 04:07:54 volumio sudo[7018]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:54 volumio sudo[7021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 07 04:07:54 volumio sudo[7021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:54 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "wizard"... Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "volumio_command_line_client"... Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "upnp"... Apr 07 04:07:54 volumio volumio[6992]: info: [1712452074903] Starting Upmpd Daemon Apr 07 04:07:54 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "my_music"... Apr 07 04:07:54 volumio volumio[6992]: info: Loading plugin "mpd"... Apr 07 04:07:55 volumio volumio[6992]: info: Creating MPD Configuration file Apr 07 04:07:55 volumio volumio[6992]: info: Loading plugin "upnp_browser"... Apr 07 04:07:55 volumio sudo[7027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:07:55 volumio sudo[7027]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:55 volumio sudo[7027]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:55 volumio sudo[7029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:07:55 volumio sudo[7029]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:07:55 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:07:55 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:07:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:07:55 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:07:55 volumio sudo[7021]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:55 volumio volumio[6992]: info: Loading plugin "networkfs"... Apr 07 04:07:55 volumio kernel: hwmon hwmon1: Voltage normalised Apr 07 04:07:55 volumio volumio[6992]: info: Starting Udev Watcher for removable devices Apr 07 04:07:56 volumio volumio[6992]: info: Ignoring mount for partition: boot Apr 07 04:07:56 volumio volumio[6992]: info: Ignoring mount for partition: volumio Apr 07 04:07:56 volumio volumio[6992]: info: Ignoring mount for partition: volumio_data Apr 07 04:07:56 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "alarm-clock"... Apr 07 04:07:56 volumio ntpd[658]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "airplay_emulation"... Apr 07 04:07:56 volumio volumio[6992]: info: Starting Shairport Sync Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "last_100"... Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "webradio"... Apr 07 04:07:56 volumio dhcpcd[764]: eth0: carrier acquired Apr 07 04:07:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 07 04:07:56 volumio dhcpcd[764]: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Apr 07 04:07:56 volumio dhcpcd[764]: eth0: IAID eb:5d:71:15 Apr 07 04:07:56 volumio dhcpcd[764]: eth0: adding address fe80::2ba1:918b:a20a:f808 Apr 07 04:07:56 volumio dhcpcd[764]: ipv6_addaddr1: Permission denied Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "i2s_dacs"... Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "volumiodiscovery"... Apr 07 04:07:56 volumio ifplugd(eth0)[662]: Link beat detected. Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:56 volumio node[6992]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** For more information see Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:56 volumio volumio[6992]: *** WARNING *** For more information see Apr 07 04:07:56 volumio node[6992]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:56 volumio node[6992]: *** WARNING *** For more information see Apr 07 04:07:56 volumio node[6992]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 04:07:56 volumio node[6992]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 04:07:56 volumio node[6992]: *** WARNING *** For more information see Apr 07 04:07:56 volumio volumio[6992]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 04:07:56 volumio volumio[6992]: info: Discovery: Started advertising with name: Volumio Apr 07 04:07:56 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 04:07:56 volumio volumio[6992]: info: Loading plugin "squeezelite_mc"... Apr 07 04:07:56 volumio ifplugd(eth0)[662]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 07 04:07:56 volumio ifplugd(eth0)[662]: client: sending commands to master dhcpcd process Apr 07 04:07:56 volumio dhcpcd[7061]: sending commands to master dhcpcd process Apr 07 04:07:56 volumio dhcpcd[764]: control command: /sbin/dhcpcd eth0 Apr 07 04:07:56 volumio dhcpcd[764]: eth0: soliciting a DHCP lease Apr 07 04:07:56 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:07:57 volumio ifplugd(eth0)[662]: Program executed successfully. Apr 07 04:07:57 volumio dhcpcd[764]: eth0: soliciting an IPv6 router Apr 07 04:07:57 volumio mpd[7034]: Apr 07 04:07 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:07:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 07 04:07:58 volumio mpd[7034]: Apr 07 04:07 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 07 04:07:58 volumio mpd[7034]: Apr 07 04:07 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 07 04:07:58 volumio systemd[1]: Started Music Player Daemon. Apr 07 04:07:58 volumio sudo[7029]: pam_unix(sudo:session): session closed for user root Apr 07 04:07:58 volumio volumio[6992]: info: Loading plugin "youtube2"... Apr 07 04:07:59 volumio ntpd[658]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 07 04:08:00 volumio volumio[6992]: info: Loading plugin "ytcr"... Apr 07 04:08:00 volumio dhcpcd[764]: eth0: offered 192.168.1.10 from 192.168.1.1 Apr 07 04:08:00 volumio dhcpcd[764]: eth0: probing address 192.168.1.10/24 Apr 07 04:08:01 volumio kernel: hwmon hwmon1: Voltage normalised Apr 07 04:08:02 volumio ntpd[658]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "outputs"... Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "albumart"... Apr 07 04:08:02 volumio wpa_supplicant[752]: wlan0: Failed to initiate sched scan Apr 07 04:08:02 volumio volumio[6992]: info: Plugin example_plugin is not enabled Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "inputs"... Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "updater_comm"... Apr 07 04:08:02 volumio volumio[6992]: info: Plugin mpdemulation is not enabled Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "rest_api"... Apr 07 04:08:02 volumio volumio[6992]: info: Loading plugin "websocket"... Apr 07 04:08:02 volumio volumio[6992]: info: Plugin minidlna is not enabled Apr 07 04:08:02 volumio volumio[6992]: info: Loading i18n strings for locale ru Apr 07 04:08:02 volumio volumio[6992]: Updating browse sources language Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:08:02 volumio volumio[6992]: Forking 3 albumart workers Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::initPlayerControls Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:02 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: Express server listening on port 3000 Apr 07 04:08:03 volumio volumio[6992]: [Metrics] WebUI: 10s 714.71ms Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::resetVolumioState Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::getcurrentVolume Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:08:03 volumio volumio[6992]: info: MPD Permissions set Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::pushState Apr 07 04:08:03 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioPushState Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::updateTrackBlock Apr 07 04:08:03 volumio volumio[6992]: info: CorePlayQueue::getTrackBlock Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:08:03 volumio volumio[6992]: info: MPD running with PID7034 Apr 07 04:08:03 volumio volumio[6992]: ,establishing connection Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: error: updateQueue error: null Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::setRepeat null single undefined Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::pushState Apr 07 04:08:03 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioPushState Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::setRandom null Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::pushState Apr 07 04:08:03 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioPushState Apr 07 04:08:03 volumio volumio[6992]: info: Setting Device type: Raspberry PI Apr 07 04:08:03 volumio volumio[6992]: info: Completed loading Core Plugins Apr 07 04:08:03 volumio volumio[6992]: info: Preparing to generate the ALSA configuration file Apr 07 04:08:03 volumio volumio[6992]: info: CoreStateMachine::pushState Apr 07 04:08:03 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioPushState Apr 07 04:08:03 volumio volumio[6992]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 07 04:08:03 volumio volumio[6992]: info: Reading ALSA contributions from plugins. Apr 07 04:08:03 volumio volumio[6992]: error: updateQueue error: null Apr 07 04:08:03 volumio volumio[6992]: info: Asound.conf file unchanged, so no further update is needed Apr 07 04:08:03 volumio volumio[6992]: info: Output device has changed, restarting MPD Apr 07 04:08:03 volumio volumio[6992]: info: Output device has changed, restarting Shairport Sync Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:08:03 volumio sudo[7145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 04:08:03 volumio sudo[7145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:08:03 volumio sudo[7145]: pam_unix(sudo:session): session closed for user root Apr 07 04:08:03 volumio sudo[7146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 04:08:03 volumio sudo[7146]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:08:03 volumio volumio[6992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:08:03 volumio volumio[6992]: info: ___________ START PLUGINS ___________ Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:08:03 volumio volumio[6992]: info: [1712452083781] CoreMusicLibrary::Adding element Media Servers Apr 07 04:08:03 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio systemd[1]: mpd.service: Succeeded. Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:08:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 04:08:03 volumio volumio[6992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:03 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 04:08:03 volumio systemd[1]: Starting Music Player Daemon... Apr 07 04:08:04 volumio volumio[6992]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:08:04 volumio volumio[6992]: info: [1712452084015] CoreMusicLibrary::Adding element Last_100 Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:08:04 volumio volumio[6992]: info: [1712452084020] CoreMusicLibrary::Adding element Webradio Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 04:08:04 volumio volumio[6992]: info: [squeezelite_mc] Starting proxy server... Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 04:08:04 volumio volumio[6992]: info: [1712452084065] CoreMusicLibrary::Adding element YouTube2 Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 04:08:04 volumio volumio[6992]: Cannot find translation for source YouTube2 Apr 07 04:08:04 volumio volumio[6992]: Starting albumart workers Apr 07 04:08:04 volumio volumio[6992]: Starting albumart workers Apr 07 04:08:04 volumio volumio[6992]: Starting albumart workers Apr 07 04:08:04 volumio ntpd[658]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 07 04:08:04 volumio volumio[6992]: info: Volumio Calling Home Apr 07 04:08:04 volumio volumio[6992]: info: [squeezelite_mc] Proxy server started on port 42997 Apr 07 04:08:04 volumio volumio[6992]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 04:08:04 volumio volumio[6992]: Unhandled rejection Error: No sockets available, cannot start. Apr 07 04:08:04 volumio volumio[6992]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 07 04:08:04 volumio volumio[6992]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 07 04:08:04 volumio volumio[6992]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 07 04:08:04 volumio volumio[6992]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 07 04:08:04 volumio volumio[6992]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 07 04:08:04 volumio volumio[6992]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 07 04:08:04 volumio volumio[6992]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 07 04:08:04 volumio volumio[6992]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 07 04:08:04 volumio volumio[6992]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 07 04:08:04 volumio volumio[6992]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 07 04:08:04 volumio volumio[6992]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 07 04:08:04 volumio volumio[6992]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 07 04:08:04 volumio volumio[6992]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 07 04:08:04 volumio volumio[6992]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 07 04:08:04 volumio volumio[6992]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 07 04:08:04 volumio volumio[6992]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (8cit1duqm3qpvfigf7d80erhh5): Apr 07 04:08:04 volumio volumio[6992]: (ConnectionError) (YouTube) Connection error in getting lounge token Apr 07 04:08:04 volumio volumio[6992]: Error info: { Apr 07 04:08:04 volumio volumio[6992]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: ConnectionError: (YouTube) Connection error in getting lounge token Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:08:04 volumio volumio[6992]: at async Promise.all (index 0) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: Going to generate fresh screen Id and try again... Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (j9au0cms8rtq71pg20otc3ea7i): Apr 07 04:08:04 volumio volumio[6992]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Apr 07 04:08:04 volumio volumio[6992]: Error info: { Apr 07 04:08:04 volumio volumio[6992]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: ConnectionError: (YouTube Music) Connection error in getting lounge token Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Apr 07 04:08:04 volumio volumio[6992]: at async Promise.all (index 1) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: Going to generate fresh screen Id and try again... Apr 07 04:08:04 volumio volumio[6992]: info: MPD Permissions set Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:08:04 volumio volumio[6992]: LoungeScreenDisconnected { Apr 07 04:08:04 volumio volumio[6992]: AID: null, Apr 07 04:08:04 volumio volumio[6992]: name: 'loungeScreenDisconnected', Apr 07 04:08:04 volumio volumio[6992]: payload: {} Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:08:04 volumio volumio[6992]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:08:04 volumio volumio[6992]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:08:04 volumio volumio[6992]: at new Promise () Apr 07 04:08:04 volumio volumio[6992]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:08:04 volumio volumio[6992]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:08:04 volumio volumio[6992]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:207:26) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async Promise.all (index 0) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] Failed to start receiver: Apr 07 04:08:04 volumio volumio[6992]: (AppError) Failed to start YouTubeApp Apr 07 04:08:04 volumio volumio[6992]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:08:04 volumio volumio[6992]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:08:04 volumio volumio[6992]: Error info: { Apr 07 04:08:04 volumio volumio[6992]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: AppError: Failed to start YouTubeApp Apr 07 04:08:04 volumio volumio[6992]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:08:04 volumio volumio[6992]: LoungeScreenDisconnected { Apr 07 04:08:04 volumio volumio[6992]: AID: null, Apr 07 04:08:04 volumio volumio[6992]: name: 'loungeScreenDisconnected', Apr 07 04:08:04 volumio volumio[6992]: payload: {} Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:08:04 volumio volumio[6992]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:18) Apr 07 04:08:04 volumio volumio[6992]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:339:67 Apr 07 04:08:04 volumio volumio[6992]: at new Promise () Apr 07 04:08:04 volumio volumio[6992]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:338:16) Apr 07 04:08:04 volumio volumio[6992]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:236:28) Apr 07 04:08:04 volumio volumio[6992]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:35 Apr 07 04:08:04 volumio volumio[6992]: at Array.forEach () Apr 07 04:08:04 volumio volumio[6992]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:24) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 07 04:08:04 volumio volumio[6992]: LoungeScreenDisconnected { Apr 07 04:08:04 volumio volumio[6992]: AID: null, Apr 07 04:08:04 volumio volumio[6992]: name: 'loungeScreenDisconnected', Apr 07 04:08:04 volumio volumio[6992]: payload: {} Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:08:04 volumio volumio[6992]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:08:04 volumio volumio[6992]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: error: [ytcr] Failed to start plugin: Apr 07 04:08:04 volumio volumio[6992]: (AppError) Failed to start YouTubeApp Apr 07 04:08:04 volumio volumio[6992]: --->(SessionError) (YouTube) Failed to establish session Apr 07 04:08:04 volumio volumio[6992]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Apr 07 04:08:04 volumio volumio[6992]: Error info: { Apr 07 04:08:04 volumio volumio[6992]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: --------->(FetchError) request to https://www.youtube.com/api/lounge/pairing/generate_screen_id failed, reason: getaddrinfo EBUSY www.youtube.com Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: AppError: Failed to start YouTubeApp Apr 07 04:08:04 volumio volumio[6992]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:125:19) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Apr 07 04:08:04 volumio volumio[6992]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 07 04:08:04 volumio volumio[6992]: LoungeScreenDisconnected { Apr 07 04:08:04 volumio volumio[6992]: AID: null, Apr 07 04:08:04 volumio volumio[6992]: name: 'loungeScreenDisconnected', Apr 07 04:08:04 volumio volumio[6992]: payload: {} Apr 07 04:08:04 volumio volumio[6992]: } Apr 07 04:08:04 volumio volumio[6992]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 07 04:08:04 volumio volumio[6992]: Stack trace: Apr 07 04:08:04 volumio volumio[6992]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 07 04:08:04 volumio volumio[6992]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 07 04:08:04 volumio volumio[6992]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 07 04:08:04 volumio volumio[6992]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 07 04:08:04 volumio volumio[6992]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 07 04:08:04 volumio volumio[6992]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 07 04:08:04 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 07 04:08:04 volumio volumio[6992]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:21 Apr 07 04:08:05 volumio volumio[6992]: info: CoreCommandRouter::volumioGetState Apr 07 04:08:05 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:05 volumio volumio[6992]: info: CoreStateMachine::pushState Apr 07 04:08:05 volumio volumio[6992]: info: CorePlayQueue::getTrack 0 Apr 07 04:08:05 volumio volumio[6992]: info: CoreCommandRouter::volumioPushState Apr 07 04:08:05 volumio volumio[6992]: info: [squeezelite_mc] Server discovery started Apr 07 04:08:05 volumio volumio[6992]: info: [squeezelite_mc] Player finder started Apr 07 04:08:05 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:05 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:05 volumio volumio[6992]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 04:08:05 volumio volumio[6992]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 07 04:08:05 volumio volumio[6992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:08:05 volumio volumio[6992]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 07 04:08:05 volumio volumio[6992]: at doSend (dgram.js:692:16) Apr 07 04:08:05 volumio volumio[6992]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 07 04:08:05 volumio volumio[6992]: at afterDns (dgram.js:638:5) Apr 07 04:08:05 volumio volumio[6992]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 07 04:08:05 volumio volumio[6992]: errno: -101, Apr 07 04:08:05 volumio volumio[6992]: code: 'ENETUNREACH', Apr 07 04:08:05 volumio volumio[6992]: syscall: 'send', Apr 07 04:08:05 volumio volumio[6992]: address: '255.255.255.255', Apr 07 04:08:05 volumio volumio[6992]: port: 3483 Apr 07 04:08:05 volumio volumio[6992]: } Apr 07 04:08:05 volumio volumio[6992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 04:08:05 volumio mpd[7160]: Apr 07 04:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 04:08:05 volumio sudo[7205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 04:07 Apr 07 04:08:05 volumio sudo[7205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 04:08:05 volumio dhcpcd[764]: eth0: leased 192.168.1.10 for 86400 seconds Apr 07 04:08:05 volumio dhcpcd[764]: eth0: adding route to 192.168.1.0/24 Apr 07 04:08:05 volumio dhcpcd[764]: eth0: adding default route via 192.168.1.1 Apr 07 04:08:05 volumio avahi-daemon[514]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.10. Apr 07 04:08:05 volumio avahi-daemon[514]: New relevant interface eth0.IPv4 for mDNS. Apr 07 04:08:05 volumio avahi-daemon[514]: Registering new address record for 192.168.1.10 on eth0.IPv4. 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"