-- 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"