-- Logs begin at Sun 2025-01-12 01:49:45 GMT, end at Mon 2025-01-13 17:10:53 GMT. -- Jan 13 17:09:01 vlounge volumio[13483]: info: Loading plugin "now_playing"... Jan 13 17:09:06 vlounge volumio[13483]: info: Loading plugin "outputs"... Jan 13 17:09:06 vlounge volumio[13483]: info: Loading plugin "albumart"... Jan 13 17:09:07 vlounge volumio[13483]: info: Plugin example_plugin is not enabled Jan 13 17:09:07 vlounge volumio[13483]: info: Loading plugin "inputs"... Jan 13 17:09:07 vlounge volumio[13483]: info: Loading plugin "updater_comm"... Jan 13 17:09:07 vlounge volumio[13483]: info: Plugin mpdemulation is not enabled Jan 13 17:09:07 vlounge volumio[13483]: info: Loading plugin "rest_api"... Jan 13 17:09:07 vlounge volumio[13483]: info: Loading plugin "websocket"... Jan 13 17:09:07 vlounge volumio[13483]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:09:07 vlounge volumio[13483]: info: Loading plugin "motherearthradio"... Jan 13 17:09:07 vlounge volumio[13483]: Forking 3 albumart workers Jan 13 17:09:08 vlounge volumio[13483]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:09:08 vlounge volumio[13483]: info: [1736788148072] [MotherEarth] API delay: 5 Jan 13 17:09:08 vlounge volumio[13483]: info: Loading plugin "touch_display"... Jan 13 17:09:08 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:08] [connect] Successful connection Jan 13 17:09:08 vlounge volumio[13483]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:09:09 vlounge volumio[13483]: info: Loading i18n strings for locale en Jan 13 17:09:09 vlounge volumio[13483]: Updating browse sources language Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:09 vlounge volumio[13483]: Starting albumart workers Jan 13 17:09:09 vlounge volumio[13483]: Starting albumart workers Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::initPlayerControls Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: Starting albumart workers Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:09 vlounge volumio[13483]: Express server listening on port 3000 Jan 13 17:09:09 vlounge volumio[13483]: [Metrics] WebUI: 16s 405.67ms Jan 13 17:09:09 vlounge volumio[13483]: info: CoreStateMachine::resetVolumioState Jan 13 17:09:09 vlounge volumio[13483]: info: CoreStateMachine::getcurrentVolume Jan 13 17:09:09 vlounge volumio[13483]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:10 vlounge sudo[13586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:09:10 vlounge volumio[13483]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:09:10 vlounge sudo[13586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:10 vlounge sudo[13588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:09:10 vlounge sudo[13588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:10 vlounge sudo[13586]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:10 vlounge sudo[13588]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:10 vlounge volumio[13483]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:10 vlounge volumio[13483]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:09:10 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736788148 101 Jan 13 17:09:10 vlounge volumio[13483]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 13 17:09:10 vlounge volumio[13483]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:10 vlounge volumio[13483]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:10 vlounge volumio[13483]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:09:10 vlounge volumio[13483]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:10 vlounge volumio[13483]: info: CoreStateMachine::pushState Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:10 vlounge volumio[13483]: info: CoreStateMachine::updateTrackBlock Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getTrackBlock Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:10 vlounge volumio[13483]: info: Cannot read play queue from file Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:10 vlounge volumio[13483]: info: Listing playlists Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CoreStateMachine::getQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:10 vlounge volumio[13483]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:10 vlounge volumio[13483]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:10 vlounge volumio[13483]: info: Listing playlists Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CoreStateMachine::getQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getQueue Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:10 vlounge volumio[13483]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:10 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:09:11 vlounge volumio[13483]: info: CoreStateMachine::pushState Jan 13 17:09:11 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:11 vlounge volumio[13483]: info: CoreStateMachine::setRandom null Jan 13 17:09:11 vlounge volumio[13483]: info: CoreStateMachine::pushState Jan 13 17:09:11 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:11 vlounge volumio[13483]: info: Setting Device type: Raspberry PI Jan 13 17:09:11 vlounge volumio[13483]: info: Completed loading Core Plugins Jan 13 17:09:11 vlounge volumio[13483]: info: Preparing to generate the ALSA configuration file Jan 13 17:09:11 vlounge volumio[13483]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:11 vlounge volumio[13483]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:09:11 vlounge volumio[13483]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:09:11 vlounge volumio[13483]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:09:11 vlounge sudo[13627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 17:09:11 vlounge sudo[13627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:11 vlounge volumio[13483]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:09:11 vlounge volumio[13483]: info: Output device has changed, restarting MPD Jan 13 17:09:11 vlounge sudo[13630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:11 vlounge sudo[13630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:11 vlounge volumio[13483]: info: Output device has changed, restarting Shairport Sync Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:11 vlounge sudo[13630]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:11 vlounge sudo[13632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:11 vlounge sudo[13632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:11 vlounge volumio[13483]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:11 vlounge volumio[13483]: info: ___________ START PLUGINS ___________ Jan 13 17:09:11 vlounge volumio[13483]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:09:11 vlounge sudo[13627]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:11 vlounge volumio[13483]: info: Creating MPD Configuration file Jan 13 17:09:11 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:09:11 vlounge sudo[13641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:11 vlounge sudo[13641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:11 vlounge sudo[13641]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:11 vlounge volumio[13483]: info: [1736788151438] CoreMusicLibrary::Adding element Media Servers Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:11 vlounge sudo[13644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:11 vlounge sudo[13644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:11 vlounge volumio[13483]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:11 vlounge volumio[13483]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:11 vlounge volumio[13483]: info: [1736788151705] CoreMusicLibrary::Adding element Last_100 Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:11 vlounge volumio[13483]: info: [1736788151709] CoreMusicLibrary::Adding element Webradio Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:11 vlounge volumio[13483]: info: Initializing BBC Radios Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:11 vlounge volumio[13483]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:09:11 vlounge volumio[13483]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:11 vlounge volumio[13483]: info: [1736788151887] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:11 vlounge volumio[13483]: Cannot find translation for source Mother Earth Radio Jan 13 17:09:11 vlounge volumio[13483]: info: Loading i18n strings for locale en Jan 13 17:09:11 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:11 vlounge volumio[13483]: info: Volumio Calling Home Jan 13 17:09:11 vlounge volumio[13483]: info: [squeezelite_mc] Proxy server started on port 41419 Jan 13 17:09:12 vlounge sudo[13667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:09:12 vlounge sudo[13667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:12 vlounge sudo[13669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:09:12 vlounge sudo[13669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:12 vlounge sudo[13672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:09:12 vlounge sudo[13672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:12 vlounge systemd[1]: Reloading. Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:12 vlounge volumio[13483]: info: [now-playing] App is listening on port 4004. Jan 13 17:09:12 vlounge volumio[13483]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:09:12 vlounge volumio[13483]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:12 vlounge volumio[13483]: info: CoreStateMachine::pushState Jan 13 17:09:12 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:12 vlounge volumio[13483]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:09:12 vlounge volumio[13483]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:09:12 vlounge volumio[13483]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:09:12 vlounge volumio[13483]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:09:12 vlounge volumio[13483]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:09:12 vlounge volumio[13483]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:09:12 vlounge volumio[13483]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:09:12 vlounge volumio[13483]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:09:12 vlounge volumio[13483]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:09:12 vlounge volumio[13483]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:09:12 vlounge volumio[13483]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:09:12 vlounge volumio[13483]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:09:12 vlounge volumio[13483]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:09:12 vlounge volumio[13483]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:09:12 vlounge volumio[13483]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:09:12 vlounge volumio[13483]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:09:12 vlounge volumio[13483]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:09:12 vlounge volumio[13483]: info: touch_display: Backlight interface detected. Jan 13 17:09:12 vlounge volumio[13483]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:12 vlounge volumio[13483]: info: MPD Permissions set Jan 13 17:09:12 vlounge volumio[13483]: info: MPD Permissions set Jan 13 17:09:12 vlounge volumio[13483]: info: Upmpdcli Daemon Started Jan 13 17:09:12 vlounge volumio[13483]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:12 vlounge volumio[13483]: info: CoreStateMachine::pushState Jan 13 17:09:12 vlounge volumio[13483]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:12 vlounge volumio[13483]: info: [squeezelite_mc] Server discovery started Jan 13 17:09:12 vlounge volumio[13483]: info: [squeezelite_mc] Player finder started Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:12 vlounge volumio[13483]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:09:12 vlounge volumio[13483]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:12 vlounge volumio[13483]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:09:12 vlounge volumio[13483]: at doSend (dgram.js:692:16) Jan 13 17:09:12 vlounge volumio[13483]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:09:12 vlounge volumio[13483]: at afterDns (dgram.js:638:5) Jan 13 17:09:12 vlounge volumio[13483]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:09:12 vlounge volumio[13483]: errno: -101, Jan 13 17:09:12 vlounge volumio[13483]: code: 'ENETUNREACH', Jan 13 17:09:12 vlounge volumio[13483]: syscall: 'send', Jan 13 17:09:12 vlounge volumio[13483]: address: '255.255.255.255', Jan 13 17:09:12 vlounge volumio[13483]: port: 3483 Jan 13 17:09:12 vlounge volumio[13483]: } Jan 13 17:09:12 vlounge volumio[13483]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:12 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:13 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:13 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:13 vlounge sudo[13672]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:13 vlounge systemd[1]: Reloading. Jan 13 17:09:13 vlounge sudo[13710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:08 Jan 13 17:09:13 vlounge sudo[13710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:13 vlounge sudo[13710]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:13 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 17:09:13 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 17:09:13 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:14 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:09:14 vlounge sudo[13669]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:14 vlounge sudo[13632]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:14 vlounge sudo[13644]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:14 vlounge sudo[13667]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:14 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:09:14 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:14 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:14 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:14 vlounge sudo[13732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:09:14 vlounge sudo[13732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:14 vlounge sudo[13732]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:14 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:09:14 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jan 13 17:09:14 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:14 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:09:14 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:09:14 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:17 vlounge volumio[13741]: info: ------------------------------------------- Jan 13 17:09:17 vlounge volumio[13741]: info: ----- Volumio3 ---- Jan 13 17:09:17 vlounge volumio[13741]: info: ------------------------------------------- Jan 13 17:09:17 vlounge volumio[13741]: info: ----- System startup ---- Jan 13 17:09:17 vlounge volumio[13741]: info: ------------------------------------------- Jan 13 17:09:17 vlounge mpd[13739]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:09:17 vlounge mpd[13739]: output: No 'audio_output' defined in config file Jan 13 17:09:17 vlounge mpd[13739]: output: Successfully detected a sndio audio device Jan 13 17:09:17 vlounge mpd[13739]: zeroconf: No global port, disabling zeroconf Jan 13 17:09:17 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:09:17 vlounge volumio[13741]: info: MYVOLUMIO Environment detected Jan 13 17:09:17 vlounge volumio[13741]: info: Plugin folders cleanup Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category audio_interface Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category miscellanea Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category music_service Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category plugins.json Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category system_controller Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category user_interface Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning into folder /data/plugins/ Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category music_service Jan 13 17:09:17 vlounge volumio[13741]: info: Scanning category user_interface Jan 13 17:09:17 vlounge volumio[13741]: info: Plugin folders cleanup completed Jan 13 17:09:17 vlounge volumio[13741]: info: ------------------------------------------- Jan 13 17:09:17 vlounge volumio[13741]: info: ----- Core plugins startup ---- Jan 13 17:09:17 vlounge volumio[13741]: info: ------------------------------------------- Jan 13 17:09:17 vlounge volumio[13741]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:09:17 vlounge volumio[13741]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:09:17 vlounge volumio[13741]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:09:17 vlounge volumio[13741]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:09:17 vlounge volumio[13741]: info: Loading plugins from folder /data/plugins/ Jan 13 17:09:17 vlounge volumio[13741]: info: Loading plugin "system"... Jan 13 17:09:17 vlounge volumio[13741]: info: Loading plugin "appearance"... Jan 13 17:09:18 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:18] [connect] Successful connection Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "network"... Jan 13 17:09:19 vlounge volumio[13741]: info: Refreshing Cached IP Addresses Jan 13 17:09:19 vlounge sudo[13769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:09:19 vlounge sudo[13769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:19 vlounge sudo[13769]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "services"... Jan 13 17:09:19 vlounge sudo[13771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:09:19 vlounge sudo[13771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "alsa_controller"... Jan 13 17:09:19 vlounge sudo[13771]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:19 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "wizard"... Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "networkfs"... Jan 13 17:09:19 vlounge volumio[13741]: info: Starting Udev Watcher for removable devices Jan 13 17:09:19 vlounge volumio[13741]: info: Ignoring mount for partition: boot Jan 13 17:09:19 vlounge volumio[13741]: info: Ignoring mount for partition: volumio Jan 13 17:09:19 vlounge volumio[13741]: info: Ignoring mount for partition: volumio_data Jan 13 17:09:19 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "upnp"... Jan 13 17:09:19 vlounge volumio[13741]: info: [1736788159405] Starting Upmpd Daemon Jan 13 17:09:19 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "my_music"... Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "mpd"... Jan 13 17:09:19 vlounge volumio[13741]: info: Loading plugin "upnp_browser"... Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "alarm-clock"... Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "airplay_emulation"... Jan 13 17:09:20 vlounge volumio[13741]: info: Starting Shairport Sync Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "last_100"... Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "webradio"... Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "i2s_dacs"... Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "volumiodiscovery"... Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** For more information see Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:20 vlounge volumio[13741]: *** WARNING *** For more information see Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** For more information see Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:20 vlounge node[13741]: *** WARNING *** For more information see Jan 13 17:09:20 vlounge volumio[13741]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:09:20 vlounge volumio[13741]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:09:20 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:20 vlounge volumio[13741]: info: Loading plugin "squeezelite_mc"... Jan 13 17:09:21 vlounge volumio[13741]: info: Loading plugin "now_playing"... Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "outputs"... Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "albumart"... Jan 13 17:09:23 vlounge volumio[13741]: info: Plugin example_plugin is not enabled Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "inputs"... Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "updater_comm"... Jan 13 17:09:23 vlounge volumio[13741]: info: Plugin mpdemulation is not enabled Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "rest_api"... Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "websocket"... Jan 13 17:09:23 vlounge volumio[13741]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "motherearthradio"... Jan 13 17:09:23 vlounge volumio[13741]: Forking 3 albumart workers Jan 13 17:09:23 vlounge volumio[13741]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:09:23 vlounge volumio[13741]: info: [1736788163768] [MotherEarth] API delay: 5 Jan 13 17:09:23 vlounge volumio[13741]: info: Loading plugin "touch_display"... Jan 13 17:09:24 vlounge volumio[13741]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:09:24 vlounge volumio[13741]: info: Loading i18n strings for locale en Jan 13 17:09:24 vlounge volumio[13741]: Updating browse sources language Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:24 vlounge volumio[13741]: Starting albumart workers Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::initPlayerControls Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:24 vlounge volumio[13741]: Express server listening on port 3000 Jan 13 17:09:24 vlounge volumio[13741]: [Metrics] WebUI: 8s 496.87ms Jan 13 17:09:24 vlounge volumio[13741]: Starting albumart workers Jan 13 17:09:24 vlounge volumio[13741]: Starting albumart workers Jan 13 17:09:24 vlounge volumio[13741]: info: CoreStateMachine::resetVolumioState Jan 13 17:09:24 vlounge volumio[13741]: info: CoreStateMachine::getcurrentVolume Jan 13 17:09:24 vlounge volumio[13741]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:24 vlounge volumio[13741]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:09:25 vlounge volumio[13741]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:09:25 vlounge volumio[13741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:25 vlounge volumio[13741]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:25 vlounge volumio[13741]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:25 vlounge volumio[13741]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:25 vlounge volumio[13741]: info: Cannot read play queue from file Jan 13 17:09:25 vlounge volumio[13741]: info: Listing playlists Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::getQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:25 vlounge volumio[13741]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:25 vlounge volumio[13741]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:25 vlounge volumio[13741]: info: Listing playlists Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::getQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getQueue Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:25 vlounge volumio[13741]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::pushState Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::updateTrackBlock Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrackBlock Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::pushState Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::setRandom null Jan 13 17:09:25 vlounge volumio[13741]: info: CoreStateMachine::pushState Jan 13 17:09:25 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:25 vlounge volumio[13741]: info: Setting Device type: Raspberry PI Jan 13 17:09:25 vlounge volumio[13741]: info: Completed loading Core Plugins Jan 13 17:09:25 vlounge volumio[13741]: info: Preparing to generate the ALSA configuration file Jan 13 17:09:25 vlounge volumio[13741]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:09:25 vlounge volumio[13741]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:09:25 vlounge volumio[13741]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:09:25 vlounge volumio[13741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:25 vlounge volumio[13741]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:09:25 vlounge volumio[13741]: info: Output device has changed, restarting MPD Jan 13 17:09:25 vlounge volumio[13741]: info: Output device has changed, restarting Shairport Sync Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:25 vlounge sudo[13860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:25 vlounge sudo[13860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:25 vlounge sudo[13862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:25 vlounge sudo[13862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:25 vlounge sudo[13860]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:25 vlounge volumio[13741]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:25 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:09:25 vlounge volumio[13741]: info: ___________ START PLUGINS ___________ Jan 13 17:09:25 vlounge volumio[13741]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:09:25 vlounge volumio[13741]: info: Creating MPD Configuration file Jan 13 17:09:25 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:25 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:25 vlounge sudo[13869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:25 vlounge volumio[13741]: info: [1736788165946] CoreMusicLibrary::Adding element Media Servers Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:25 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:25 vlounge sudo[13869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:25 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:25 vlounge sudo[13869]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:25 vlounge sudo[13872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:25 vlounge sudo[13872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:26 vlounge volumio[13741]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:26 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:09:26 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:26 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:26 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:26 vlounge volumio[13741]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:26 vlounge volumio[13741]: info: [1736788166077] CoreMusicLibrary::Adding element Last_100 Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:26 vlounge volumio[13741]: info: [1736788166082] CoreMusicLibrary::Adding element Webradio Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:26 vlounge volumio[13741]: info: Initializing BBC Radios Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:26 vlounge sudo[13879]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:09:26 vlounge sudo[13879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:26 vlounge sudo[13879]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:26 vlounge volumio[13741]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:09:26 vlounge volumio[13741]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:26 vlounge volumio[13741]: info: [1736788166240] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:26 vlounge volumio[13741]: Cannot find translation for source Mother Earth Radio Jan 13 17:09:26 vlounge volumio[13741]: info: Loading i18n strings for locale en Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:26 vlounge volumio[13741]: info: Volumio Calling Home Jan 13 17:09:26 vlounge volumio[13741]: info: [squeezelite_mc] Proxy server started on port 37785 Jan 13 17:09:26 vlounge sudo[13940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:09:26 vlounge sudo[13940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:26 vlounge sudo[13942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:09:26 vlounge sudo[13942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:26 vlounge sudo[13945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:09:26 vlounge sudo[13945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:26 vlounge systemd[1]: Reloading. Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:26 vlounge volumio[13741]: info: [now-playing] App is listening on port 4004. Jan 13 17:09:26 vlounge volumio[13741]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:09:26 vlounge volumio[13741]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:26 vlounge volumio[13741]: info: CoreStateMachine::pushState Jan 13 17:09:26 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:26 vlounge volumio[13741]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:09:26 vlounge volumio[13741]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:09:26 vlounge volumio[13741]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:09:26 vlounge volumio[13741]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:09:26 vlounge volumio[13741]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:09:26 vlounge volumio[13741]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:09:26 vlounge volumio[13741]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:09:26 vlounge volumio[13741]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:09:26 vlounge volumio[13741]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:09:26 vlounge volumio[13741]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:09:26 vlounge volumio[13741]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:09:26 vlounge volumio[13741]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:09:26 vlounge volumio[13741]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:09:26 vlounge volumio[13741]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:09:26 vlounge volumio[13741]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:09:26 vlounge volumio[13741]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:09:26 vlounge volumio[13741]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:09:26 vlounge volumio[13741]: info: touch_display: Backlight interface detected. Jan 13 17:09:26 vlounge volumio[13741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:26 vlounge volumio[13741]: info: MPD Permissions set Jan 13 17:09:26 vlounge volumio[13741]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:09:26 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:26 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 13 17:09:27 vlounge volumio[13741]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:27 vlounge sudo[13972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 17:09:27 vlounge volumio[13741]: info: CoreStateMachine::pushState Jan 13 17:09:27 vlounge volumio[13741]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:27 vlounge sudo[13972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:27 vlounge sudo[13972]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:27 vlounge volumio[13741]: info: [squeezelite_mc] Server discovery started Jan 13 17:09:27 vlounge volumio[13741]: info: [squeezelite_mc] Player finder started Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:27 vlounge volumio[13741]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:09:27 vlounge volumio[13741]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:27 vlounge volumio[13741]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:09:27 vlounge volumio[13741]: at doSend (dgram.js:692:16) Jan 13 17:09:27 vlounge volumio[13741]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:09:27 vlounge volumio[13741]: at afterDns (dgram.js:638:5) Jan 13 17:09:27 vlounge volumio[13741]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:09:27 vlounge volumio[13741]: errno: -101, Jan 13 17:09:27 vlounge volumio[13741]: code: 'ENETUNREACH', Jan 13 17:09:27 vlounge volumio[13741]: syscall: 'send', Jan 13 17:09:27 vlounge volumio[13741]: address: '255.255.255.255', Jan 13 17:09:27 vlounge volumio[13741]: port: 3483 Jan 13 17:09:27 vlounge volumio[13741]: } Jan 13 17:09:27 vlounge volumio[13741]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:27 vlounge sudo[13945]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:27 vlounge systemd[1]: Reloading. Jan 13 17:09:28 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:28 vlounge sudo[13999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:08 Jan 13 17:09:28 vlounge sudo[13999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:28 vlounge sudo[13999]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:28 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:09:28 vlounge sudo[13862]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:28 vlounge sudo[13942]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:28 vlounge sudo[13940]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:28 vlounge sudo[13872]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:28 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:09:28 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:28 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:09:28 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jan 13 17:09:28 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:09:28 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:09:30 vlounge mpd[13894]: Jan 13 17:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:09:31 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:31] [info] asio async_connect error: system:111 (Connection refused) Jan 13 17:09:31 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 17:09:31 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:31] [error] handle_connect error: Underlying Transport Error Jan 13 17:09:31 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:09:32 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:33 vlounge volumio[14007]: info: ------------------------------------------- Jan 13 17:09:33 vlounge volumio[14007]: info: ----- Volumio3 ---- Jan 13 17:09:33 vlounge volumio[14007]: info: ------------------------------------------- Jan 13 17:09:33 vlounge volumio[14007]: info: ----- System startup ---- Jan 13 17:09:33 vlounge volumio[14007]: info: ------------------------------------------- Jan 13 17:09:34 vlounge volumio[14007]: info: MYVOLUMIO Environment detected Jan 13 17:09:34 vlounge volumio[14007]: info: Plugin folders cleanup Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category audio_interface Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category miscellanea Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category music_service Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category plugins.json Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category system_controller Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category user_interface Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning into folder /data/plugins/ Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category music_service Jan 13 17:09:34 vlounge volumio[14007]: info: Scanning category user_interface Jan 13 17:09:34 vlounge volumio[14007]: info: Plugin folders cleanup completed Jan 13 17:09:34 vlounge volumio[14007]: info: ------------------------------------------- Jan 13 17:09:34 vlounge volumio[14007]: info: ----- Core plugins startup ---- Jan 13 17:09:34 vlounge volumio[14007]: info: ------------------------------------------- Jan 13 17:09:34 vlounge volumio[14007]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:09:34 vlounge volumio[14007]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:09:34 vlounge volumio[14007]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:09:34 vlounge volumio[14007]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:09:34 vlounge volumio[14007]: info: Loading plugins from folder /data/plugins/ Jan 13 17:09:34 vlounge volumio[14007]: info: Loading plugin "system"... Jan 13 17:09:34 vlounge volumio[14007]: info: Loading plugin "appearance"... Jan 13 17:09:35 vlounge volumio[14007]: info: Loading plugin "network"... Jan 13 17:09:35 vlounge volumio[14007]: info: Refreshing Cached IP Addresses Jan 13 17:09:35 vlounge sudo[14039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:09:35 vlounge sudo[14039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:35 vlounge sudo[14039]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:35 vlounge volumio[14007]: info: Loading plugin "services"... Jan 13 17:09:35 vlounge volumio[14007]: info: Loading plugin "alsa_controller"... Jan 13 17:09:35 vlounge sudo[14041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:09:35 vlounge sudo[14041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:35 vlounge sudo[14041]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:36 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "wizard"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "networkfs"... Jan 13 17:09:36 vlounge volumio[14007]: info: Starting Udev Watcher for removable devices Jan 13 17:09:36 vlounge volumio[14007]: info: Ignoring mount for partition: boot Jan 13 17:09:36 vlounge volumio[14007]: info: Ignoring mount for partition: volumio Jan 13 17:09:36 vlounge volumio[14007]: info: Ignoring mount for partition: volumio_data Jan 13 17:09:36 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "upnp"... Jan 13 17:09:36 vlounge volumio[14007]: info: [1736788176099] Starting Upmpd Daemon Jan 13 17:09:36 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "my_music"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "mpd"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "upnp_browser"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "alarm-clock"... Jan 13 17:09:36 vlounge volumio[14007]: info: Loading plugin "airplay_emulation"... Jan 13 17:09:37 vlounge volumio[14007]: info: Starting Shairport Sync Jan 13 17:09:37 vlounge volumio[14007]: info: Loading plugin "last_100"... Jan 13 17:09:37 vlounge volumio[14007]: info: Loading plugin "webradio"... Jan 13 17:09:37 vlounge volumio[14007]: info: Loading plugin "i2s_dacs"... Jan 13 17:09:37 vlounge volumio[14007]: info: Loading plugin "volumiodiscovery"... Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** For more information see Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:37 vlounge volumio[14007]: *** WARNING *** For more information see Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** For more information see Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:37 vlounge node[14007]: *** WARNING *** For more information see Jan 13 17:09:37 vlounge volumio[14007]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:09:37 vlounge volumio[14007]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:09:37 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:37 vlounge volumio[14007]: info: Loading plugin "squeezelite_mc"... Jan 13 17:09:38 vlounge volumio[14007]: info: Loading plugin "now_playing"... Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "outputs"... Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "albumart"... Jan 13 17:09:39 vlounge volumio[14007]: info: Plugin example_plugin is not enabled Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "inputs"... Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "updater_comm"... Jan 13 17:09:39 vlounge volumio[14007]: info: Plugin mpdemulation is not enabled Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "rest_api"... Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "websocket"... Jan 13 17:09:39 vlounge volumio[14007]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:09:39 vlounge volumio[14007]: info: Loading plugin "motherearthradio"... Jan 13 17:09:40 vlounge volumio[14007]: Forking 3 albumart workers Jan 13 17:09:40 vlounge volumio[14007]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:09:40 vlounge volumio[14007]: info: [1736788180165] [MotherEarth] API delay: 5 Jan 13 17:09:40 vlounge volumio[14007]: info: Loading plugin "touch_display"... Jan 13 17:09:40 vlounge volumio[14007]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:09:40 vlounge volumio[14007]: info: Loading i18n strings for locale en Jan 13 17:09:40 vlounge volumio[14007]: Updating browse sources language Jan 13 17:09:40 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::initPlayerControls Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: Express server listening on port 3000 Jan 13 17:09:41 vlounge volumio[14007]: [Metrics] WebUI: 7s 950.08ms Jan 13 17:09:41 vlounge volumio[14007]: Starting albumart workers Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::resetVolumioState Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::getcurrentVolume Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:41 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:41] [connect] Successful connection Jan 13 17:09:41 vlounge volumio[14007]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:09:41 vlounge volumio[14007]: Starting albumart workers Jan 13 17:09:41 vlounge volumio[14007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:09:41 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736788181 101 Jan 13 17:09:41 vlounge volumio[14007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 13 17:09:41 vlounge volumio[14007]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:41 vlounge volumio[14007]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:41 vlounge volumio[14007]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:41 vlounge volumio[14007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:09:41 vlounge volumio[14007]: Starting albumart workers Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:41 vlounge volumio[14007]: info: Cannot read play queue from file Jan 13 17:09:41 vlounge volumio[14007]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::pushState Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::updateTrackBlock Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrackBlock Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::pushState Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::setRandom null Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::pushState Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:41 vlounge volumio[14007]: info: Setting Device type: Raspberry PI Jan 13 17:09:41 vlounge volumio[14007]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:09:41 vlounge volumio[14007]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:09:41 vlounge volumio[14007]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:09:41 vlounge volumio[14007]: info: Listing playlists Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::getQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:41 vlounge volumio[14007]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:41 vlounge volumio[14007]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:41 vlounge volumio[14007]: info: Listing playlists Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CoreStateMachine::getQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getQueue Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:41 vlounge volumio[14007]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:41 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:41 vlounge volumio[14007]: info: Completed loading Core Plugins Jan 13 17:09:41 vlounge volumio[14007]: info: Preparing to generate the ALSA configuration file Jan 13 17:09:41 vlounge volumio[14007]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:42 vlounge volumio[14007]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:09:42 vlounge volumio[14007]: info: Output device has changed, restarting MPD Jan 13 17:09:42 vlounge volumio[14007]: info: Output device has changed, restarting Shairport Sync Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:42 vlounge sudo[14111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:42 vlounge sudo[14111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge sudo[14111]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:42 vlounge sudo[14113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:42 vlounge sudo[14113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge volumio[14007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:42 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:09:42 vlounge volumio[14007]: info: ___________ START PLUGINS ___________ Jan 13 17:09:42 vlounge volumio[14007]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:09:42 vlounge volumio[14007]: info: Creating MPD Configuration file Jan 13 17:09:42 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:42 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:42 vlounge sudo[14125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:42 vlounge sudo[14125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:42 vlounge volumio[14007]: info: [1736788182335] CoreMusicLibrary::Adding element Media Servers Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:42 vlounge sudo[14125]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:42 vlounge sudo[14128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:42 vlounge sudo[14128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:09:42 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:42 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:42 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:42 vlounge volumio[14007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:42 vlounge volumio[14007]: info: [1736788182543] CoreMusicLibrary::Adding element Last_100 Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:42 vlounge volumio[14007]: info: [1736788182548] CoreMusicLibrary::Adding element Webradio Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:42 vlounge sudo[14138]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:09:42 vlounge sudo[14138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:42 vlounge sudo[14138]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:42 vlounge volumio[14007]: info: Initializing BBC Radios Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:42 vlounge volumio[14007]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:09:42 vlounge volumio[14007]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:42 vlounge volumio[14007]: info: [1736788182711] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:42 vlounge volumio[14007]: Cannot find translation for source Mother Earth Radio Jan 13 17:09:42 vlounge volumio[14007]: info: Loading i18n strings for locale en Jan 13 17:09:42 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:42 vlounge volumio[14007]: info: Volumio Calling Home Jan 13 17:09:42 vlounge volumio[14007]: info: [squeezelite_mc] Proxy server started on port 44399 Jan 13 17:09:42 vlounge sudo[14169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:09:42 vlounge sudo[14169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge sudo[14171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:09:42 vlounge sudo[14171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge sudo[14174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:09:42 vlounge sudo[14174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:42 vlounge sudo[14169]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:43 vlounge systemd[1]: Reloading. Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:43 vlounge volumio[14007]: info: [now-playing] App is listening on port 4004. Jan 13 17:09:43 vlounge volumio[14007]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:09:43 vlounge volumio[14007]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:43 vlounge volumio[14007]: info: CoreStateMachine::pushState Jan 13 17:09:43 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:43 vlounge volumio[14007]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:09:43 vlounge volumio[14007]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:09:43 vlounge volumio[14007]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:09:43 vlounge volumio[14007]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:09:43 vlounge volumio[14007]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:09:43 vlounge volumio[14007]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:09:43 vlounge volumio[14007]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:09:43 vlounge volumio[14007]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:09:43 vlounge volumio[14007]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:09:43 vlounge volumio[14007]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:09:43 vlounge volumio[14007]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:09:43 vlounge volumio[14007]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:09:43 vlounge volumio[14007]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:09:43 vlounge volumio[14007]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:09:43 vlounge volumio[14007]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:09:43 vlounge volumio[14007]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:09:43 vlounge volumio[14007]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:09:43 vlounge volumio[14007]: info: touch_display: Backlight interface detected. Jan 13 17:09:43 vlounge volumio[14007]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:43 vlounge volumio[14007]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:09:43 vlounge volumio[14007]: info: MPD Permissions set Jan 13 17:09:43 vlounge volumio[14007]: info: MPD Permissions set Jan 13 17:09:43 vlounge volumio[14007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:09:43 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:43 vlounge volumio[14007]: info: CoreStateMachine::pushState Jan 13 17:09:43 vlounge volumio[14007]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:43 vlounge volumio[14007]: info: [squeezelite_mc] Server discovery started Jan 13 17:09:43 vlounge volumio[14007]: info: [squeezelite_mc] Player finder started Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:43 vlounge volumio[14007]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:09:43 vlounge volumio[14007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:43 vlounge volumio[14007]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:09:43 vlounge volumio[14007]: at doSend (dgram.js:692:16) Jan 13 17:09:43 vlounge volumio[14007]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:09:43 vlounge volumio[14007]: at afterDns (dgram.js:638:5) Jan 13 17:09:43 vlounge volumio[14007]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:09:43 vlounge volumio[14007]: errno: -101, Jan 13 17:09:43 vlounge volumio[14007]: code: 'ENETUNREACH', Jan 13 17:09:43 vlounge volumio[14007]: syscall: 'send', Jan 13 17:09:43 vlounge volumio[14007]: address: '255.255.255.255', Jan 13 17:09:43 vlounge volumio[14007]: port: 3483 Jan 13 17:09:43 vlounge volumio[14007]: } Jan 13 17:09:43 vlounge volumio[14007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:44 vlounge sudo[14174]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:44 vlounge systemd[1]: Reloading. Jan 13 17:09:44 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:44 vlounge sudo[14224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:08 Jan 13 17:09:44 vlounge sudo[14224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:44 vlounge mpd[14154]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:09:44 vlounge mpd[14154]: output: No 'audio_output' defined in config file Jan 13 17:09:44 vlounge mpd[14154]: output: Successfully detected a sndio audio device Jan 13 17:09:44 vlounge mpd[14154]: zeroconf: No global port, disabling zeroconf Jan 13 17:09:44 vlounge sudo[14224]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:44 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 17:09:44 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 17:09:45 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:09:45 vlounge sudo[14113]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:45 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:09:45 vlounge sudo[14171]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:45 vlounge sudo[14128]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:45 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:09:45 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:45 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:45 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:09:45 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jan 13 17:09:45 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:45 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:09:45 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:09:45 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:47 vlounge volumio[14241]: info: ------------------------------------------- Jan 13 17:09:47 vlounge volumio[14241]: info: ----- Volumio3 ---- Jan 13 17:09:47 vlounge volumio[14241]: info: ------------------------------------------- Jan 13 17:09:47 vlounge volumio[14241]: info: ----- System startup ---- Jan 13 17:09:47 vlounge volumio[14241]: info: ------------------------------------------- Jan 13 17:09:48 vlounge volumio[14241]: info: MYVOLUMIO Environment detected Jan 13 17:09:48 vlounge volumio[14241]: info: Plugin folders cleanup Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category audio_interface Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category miscellanea Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category music_service Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category plugins.json Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category system_controller Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category user_interface Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning into folder /data/plugins/ Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category music_service Jan 13 17:09:48 vlounge volumio[14241]: info: Scanning category user_interface Jan 13 17:09:48 vlounge volumio[14241]: info: Plugin folders cleanup completed Jan 13 17:09:48 vlounge volumio[14241]: info: ------------------------------------------- Jan 13 17:09:48 vlounge volumio[14241]: info: ----- Core plugins startup ---- Jan 13 17:09:48 vlounge volumio[14241]: info: ------------------------------------------- Jan 13 17:09:48 vlounge volumio[14241]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:09:48 vlounge volumio[14241]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:09:48 vlounge volumio[14241]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:09:48 vlounge volumio[14241]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:09:48 vlounge volumio[14241]: info: Loading plugins from folder /data/plugins/ Jan 13 17:09:48 vlounge volumio[14241]: info: Loading plugin "system"... Jan 13 17:09:48 vlounge volumio[14241]: info: Loading plugin "appearance"... Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "network"... Jan 13 17:09:49 vlounge volumio[14241]: info: Refreshing Cached IP Addresses Jan 13 17:09:49 vlounge sudo[14267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:09:49 vlounge sudo[14267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:49 vlounge sudo[14267]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "services"... Jan 13 17:09:49 vlounge sudo[14269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:09:49 vlounge sudo[14269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "alsa_controller"... Jan 13 17:09:49 vlounge sudo[14269]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:49 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "wizard"... Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "networkfs"... Jan 13 17:09:49 vlounge volumio[14241]: info: Starting Udev Watcher for removable devices Jan 13 17:09:49 vlounge volumio[14241]: info: Ignoring mount for partition: boot Jan 13 17:09:49 vlounge volumio[14241]: info: Ignoring mount for partition: volumio Jan 13 17:09:49 vlounge volumio[14241]: info: Ignoring mount for partition: volumio_data Jan 13 17:09:49 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "upnp"... Jan 13 17:09:49 vlounge volumio[14241]: info: [1736788189633] Starting Upmpd Daemon Jan 13 17:09:49 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "my_music"... Jan 13 17:09:49 vlounge volumio[14241]: info: Loading plugin "mpd"... Jan 13 17:09:49 vlounge volumio-remote-updater[600]: [2025-01-13 17:09:49] [connect] Successful connection Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "upnp_browser"... Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "alarm-clock"... Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "airplay_emulation"... Jan 13 17:09:50 vlounge volumio[14241]: info: Starting Shairport Sync Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "last_100"... Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "webradio"... Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "i2s_dacs"... Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "volumiodiscovery"... Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** For more information see Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:50 vlounge volumio[14241]: *** WARNING *** For more information see Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** For more information see Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:09:50 vlounge node[14241]: *** WARNING *** For more information see Jan 13 17:09:50 vlounge volumio[14241]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:09:50 vlounge volumio[14241]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:09:50 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:09:50 vlounge volumio[14241]: info: Loading plugin "squeezelite_mc"... Jan 13 17:09:51 vlounge volumio[14241]: info: Loading plugin "now_playing"... Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "outputs"... Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "albumart"... Jan 13 17:09:53 vlounge volumio[14241]: info: Plugin example_plugin is not enabled Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "inputs"... Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "updater_comm"... Jan 13 17:09:53 vlounge volumio[14241]: info: Plugin mpdemulation is not enabled Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "rest_api"... Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "websocket"... Jan 13 17:09:53 vlounge volumio[14241]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "motherearthradio"... Jan 13 17:09:53 vlounge volumio[14241]: Forking 3 albumart workers Jan 13 17:09:53 vlounge volumio[14241]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:09:53 vlounge volumio[14241]: info: [1736788193672] [MotherEarth] API delay: 5 Jan 13 17:09:53 vlounge volumio[14241]: info: Loading plugin "touch_display"... Jan 13 17:09:54 vlounge volumio[14241]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:09:54 vlounge volumio[14241]: info: Loading i18n strings for locale en Jan 13 17:09:54 vlounge volumio[14241]: Updating browse sources language Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::initPlayerControls Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:54 vlounge volumio[14241]: Express server listening on port 3000 Jan 13 17:09:54 vlounge volumio[14241]: [Metrics] WebUI: 7s 866.38ms Jan 13 17:09:54 vlounge volumio[14241]: info: CoreStateMachine::resetVolumioState Jan 13 17:09:54 vlounge volumio[14241]: info: CoreStateMachine::getcurrentVolume Jan 13 17:09:54 vlounge volumio[14241]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:54 vlounge volumio[14241]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:09:54 vlounge volumio[14241]: Starting albumart workers Jan 13 17:09:54 vlounge volumio[14241]: Starting albumart workers Jan 13 17:09:54 vlounge volumio[14241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:09:54 vlounge volumio[14241]: Starting albumart workers Jan 13 17:09:54 vlounge volumio[14241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:55 vlounge volumio[14241]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:55 vlounge volumio[14241]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:55 vlounge volumio[14241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:55 vlounge volumio[14241]: info: Cannot read play queue from file Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::pushState Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::setRandom null Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::pushState Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:55 vlounge volumio[14241]: info: Setting Device type: Raspberry PI Jan 13 17:09:55 vlounge volumio[14241]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:09:55 vlounge volumio[14241]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:09:55 vlounge volumio[14241]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:09:55 vlounge volumio[14241]: info: Listing playlists Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::getQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:55 vlounge volumio[14241]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:09:55 vlounge volumio[14241]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:09:55 vlounge volumio[14241]: info: Listing playlists Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::getQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getQueue Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioGetState Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:55 vlounge volumio[14241]: info: Completed loading Core Plugins Jan 13 17:09:55 vlounge volumio[14241]: info: Preparing to generate the ALSA configuration file Jan 13 17:09:55 vlounge volumio[14241]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::pushState Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:55 vlounge volumio[14241]: info: CoreStateMachine::updateTrackBlock Jan 13 17:09:55 vlounge volumio[14241]: info: CorePlayQueue::getTrackBlock Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:55 vlounge volumio[14241]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:09:55 vlounge volumio[14241]: info: Output device has changed, restarting MPD Jan 13 17:09:55 vlounge volumio[14241]: info: Output device has changed, restarting Shairport Sync Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:55 vlounge sudo[14334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:55 vlounge sudo[14334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:55 vlounge sudo[14336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:55 vlounge sudo[14334]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:55 vlounge sudo[14336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:55 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:09:55 vlounge volumio[14241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:55 vlounge volumio[14241]: info: ___________ START PLUGINS ___________ Jan 13 17:09:55 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:55 vlounge volumio[14241]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:09:55 vlounge volumio[14241]: info: Creating MPD Configuration file Jan 13 17:09:55 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:55 vlounge volumio[14241]: info: [1736788195817] CoreMusicLibrary::Adding element Media Servers Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:55 vlounge sudo[14344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:09:55 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:55 vlounge sudo[14344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:55 vlounge sudo[14344]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:55 vlounge sudo[14350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:09:55 vlounge sudo[14350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:55 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:09:55 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:09:55 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:09:55 vlounge volumio[14241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:55 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:56 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:09:56 vlounge volumio[14241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:56 vlounge volumio[14241]: info: [1736788196060] CoreMusicLibrary::Adding element Last_100 Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:56 vlounge volumio[14241]: info: [1736788196064] CoreMusicLibrary::Adding element Webradio Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:56 vlounge volumio[14241]: info: Initializing BBC Radios Jan 13 17:09:56 vlounge sudo[14369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:09:56 vlounge sudo[14369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:56 vlounge sudo[14369]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:09:56 vlounge volumio[14241]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:09:56 vlounge volumio[14241]: info: [1736788196215] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:09:56 vlounge volumio[14241]: Cannot find translation for source Mother Earth Radio Jan 13 17:09:56 vlounge volumio[14241]: info: Loading i18n strings for locale en Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:09:56 vlounge volumio[14241]: info: Volumio Calling Home Jan 13 17:09:56 vlounge volumio[14241]: info: [squeezelite_mc] Proxy server started on port 36665 Jan 13 17:09:56 vlounge sudo[14397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:09:56 vlounge sudo[14397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:56 vlounge sudo[14399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:09:56 vlounge sudo[14399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:56 vlounge sudo[14402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:09:56 vlounge sudo[14402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:56 vlounge sudo[14397]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:56 vlounge systemd[1]: Reloading. Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:09:56 vlounge volumio[14241]: info: [now-playing] App is listening on port 4004. Jan 13 17:09:56 vlounge volumio[14241]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:09:56 vlounge volumio[14241]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:09:56 vlounge volumio[14241]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:09:56 vlounge volumio[14241]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:09:56 vlounge volumio[14241]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:09:56 vlounge volumio[14241]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:09:56 vlounge volumio[14241]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:09:56 vlounge volumio[14241]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:09:56 vlounge volumio[14241]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:09:56 vlounge volumio[14241]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:09:56 vlounge volumio[14241]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:09:56 vlounge volumio[14241]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:09:56 vlounge volumio[14241]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:09:56 vlounge volumio[14241]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:09:56 vlounge volumio[14241]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:09:56 vlounge volumio[14241]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:09:56 vlounge volumio[14241]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:09:56 vlounge volumio[14241]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:09:56 vlounge volumio[14241]: info: touch_display: Backlight interface detected. Jan 13 17:09:56 vlounge volumio[14241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:56 vlounge volumio[14241]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:09:56 vlounge volumio[14241]: info: MPD Permissions set Jan 13 17:09:56 vlounge volumio[14241]: info: MPD Permissions set Jan 13 17:09:56 vlounge volumio[14241]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:09:56 vlounge volumio[14241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:56 vlounge volumio[14241]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:09:57 vlounge volumio[14241]: info: CoreStateMachine::pushState Jan 13 17:09:57 vlounge volumio[14241]: info: CorePlayQueue::getTrack 0 Jan 13 17:09:57 vlounge volumio[14241]: info: CoreCommandRouter::volumioPushState Jan 13 17:09:57 vlounge volumio[14241]: info: [squeezelite_mc] Server discovery started Jan 13 17:09:57 vlounge volumio[14241]: info: [squeezelite_mc] Player finder started Jan 13 17:09:57 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:57 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:57 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:09:57 vlounge volumio[14241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:09:57 vlounge volumio[14241]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:09:57 vlounge volumio[14241]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:57 vlounge volumio[14241]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:09:57 vlounge volumio[14241]: at doSend (dgram.js:692:16) Jan 13 17:09:57 vlounge volumio[14241]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:09:57 vlounge volumio[14241]: at afterDns (dgram.js:638:5) Jan 13 17:09:57 vlounge volumio[14241]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:09:57 vlounge volumio[14241]: errno: -101, Jan 13 17:09:57 vlounge volumio[14241]: code: 'ENETUNREACH', Jan 13 17:09:57 vlounge volumio[14241]: syscall: 'send', Jan 13 17:09:57 vlounge volumio[14241]: address: '255.255.255.255', Jan 13 17:09:57 vlounge volumio[14241]: port: 3483 Jan 13 17:09:57 vlounge volumio[14241]: } Jan 13 17:09:57 vlounge volumio[14241]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:09:57 vlounge systemd[1]: Reloading. Jan 13 17:09:57 vlounge sudo[14402]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:57 vlounge sudo[14454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:08 Jan 13 17:09:57 vlounge sudo[14454]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:09:57 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:09:58 vlounge sudo[14454]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:58 vlounge mpd[14392]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:09:58 vlounge mpd[14392]: output: No 'audio_output' defined in config file Jan 13 17:09:58 vlounge mpd[14392]: output: Successfully detected a sndio audio device Jan 13 17:09:58 vlounge mpd[14392]: zeroconf: No global port, disabling zeroconf Jan 13 17:09:58 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:09:58 vlounge sudo[14336]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:58 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:09:58 vlounge sudo[14350]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:58 vlounge sudo[14399]: pam_unix(sudo:session): session closed for user root Jan 13 17:09:58 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:09:58 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:58 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:09:58 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:09:58 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jan 13 17:09:58 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:09:58 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:09:58 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:09:58 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:00 vlounge volumio[14470]: info: ------------------------------------------- Jan 13 17:10:00 vlounge volumio[14470]: info: ----- Volumio3 ---- Jan 13 17:10:00 vlounge volumio[14470]: info: ------------------------------------------- Jan 13 17:10:00 vlounge volumio[14470]: info: ----- System startup ---- Jan 13 17:10:00 vlounge volumio[14470]: info: ------------------------------------------- Jan 13 17:10:01 vlounge volumio[14470]: info: MYVOLUMIO Environment detected Jan 13 17:10:01 vlounge volumio[14470]: info: Plugin folders cleanup Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category audio_interface Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category miscellanea Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category music_service Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category plugins.json Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category system_controller Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category user_interface Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning into folder /data/plugins/ Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category music_service Jan 13 17:10:01 vlounge volumio[14470]: info: Scanning category user_interface Jan 13 17:10:01 vlounge volumio[14470]: info: Plugin folders cleanup completed Jan 13 17:10:01 vlounge volumio[14470]: info: ------------------------------------------- Jan 13 17:10:01 vlounge volumio[14470]: info: ----- Core plugins startup ---- Jan 13 17:10:01 vlounge volumio[14470]: info: ------------------------------------------- Jan 13 17:10:01 vlounge volumio[14470]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:10:01 vlounge volumio[14470]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:10:01 vlounge volumio[14470]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:10:01 vlounge volumio[14470]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:10:01 vlounge volumio[14470]: info: Loading plugins from folder /data/plugins/ Jan 13 17:10:01 vlounge volumio[14470]: info: Loading plugin "system"... Jan 13 17:10:01 vlounge volumio[14470]: info: Loading plugin "appearance"... Jan 13 17:10:02 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:02] [connect] Successful connection Jan 13 17:10:02 vlounge volumio[14470]: info: Loading plugin "network"... Jan 13 17:10:02 vlounge volumio[14470]: info: Refreshing Cached IP Addresses Jan 13 17:10:02 vlounge sudo[14499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:10:02 vlounge sudo[14499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:02 vlounge sudo[14499]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "services"... Jan 13 17:10:03 vlounge sudo[14501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "alsa_controller"... Jan 13 17:10:03 vlounge sudo[14501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:03 vlounge sudo[14501]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:03 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "wizard"... Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "networkfs"... Jan 13 17:10:03 vlounge volumio[14470]: info: Starting Udev Watcher for removable devices Jan 13 17:10:03 vlounge volumio[14470]: info: Ignoring mount for partition: boot Jan 13 17:10:03 vlounge volumio[14470]: info: Ignoring mount for partition: volumio Jan 13 17:10:03 vlounge volumio[14470]: info: Ignoring mount for partition: volumio_data Jan 13 17:10:03 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "upnp"... Jan 13 17:10:03 vlounge volumio[14470]: info: [1736788203182] Starting Upmpd Daemon Jan 13 17:10:03 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "my_music"... Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "mpd"... Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "upnp_browser"... Jan 13 17:10:03 vlounge volumio[14470]: info: Loading plugin "alarm-clock"... Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "airplay_emulation"... Jan 13 17:10:04 vlounge volumio[14470]: info: Starting Shairport Sync Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "last_100"... Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "webradio"... Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "i2s_dacs"... Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "volumiodiscovery"... Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** For more information see Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:04 vlounge volumio[14470]: *** WARNING *** For more information see Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** For more information see Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:04 vlounge node[14470]: *** WARNING *** For more information see Jan 13 17:10:04 vlounge volumio[14470]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:10:04 vlounge volumio[14470]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:10:04 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:04 vlounge volumio[14470]: info: Loading plugin "squeezelite_mc"... Jan 13 17:10:05 vlounge volumio[14470]: info: Loading plugin "now_playing"... Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "outputs"... Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "albumart"... Jan 13 17:10:06 vlounge volumio[14470]: info: Plugin example_plugin is not enabled Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "inputs"... Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "updater_comm"... Jan 13 17:10:06 vlounge volumio[14470]: info: Plugin mpdemulation is not enabled Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "rest_api"... Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "websocket"... Jan 13 17:10:06 vlounge volumio[14470]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:10:06 vlounge volumio[14470]: info: Loading plugin "motherearthradio"... Jan 13 17:10:07 vlounge volumio[14470]: Forking 3 albumart workers Jan 13 17:10:07 vlounge volumio[14470]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:10:07 vlounge volumio[14470]: info: [1736788207211] [MotherEarth] API delay: 5 Jan 13 17:10:07 vlounge volumio[14470]: info: Loading plugin "touch_display"... Jan 13 17:10:07 vlounge volumio[14470]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:10:07 vlounge volumio[14470]: info: Loading i18n strings for locale en Jan 13 17:10:07 vlounge volumio[14470]: Updating browse sources language Jan 13 17:10:07 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::initPlayerControls Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: Express server listening on port 3000 Jan 13 17:10:08 vlounge volumio[14470]: [Metrics] WebUI: 7s 865.32ms Jan 13 17:10:08 vlounge volumio[14470]: Starting albumart workers Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::resetVolumioState Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::getcurrentVolume Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:08 vlounge volumio[14470]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:10:08 vlounge volumio[14470]: Starting albumart workers Jan 13 17:10:08 vlounge volumio[14470]: Starting albumart workers Jan 13 17:10:08 vlounge volumio[14470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:10:08 vlounge volumio[14470]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:08 vlounge volumio[14470]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:08 vlounge volumio[14470]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:08 vlounge volumio[14470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:08 vlounge volumio[14470]: info: Cannot read play queue from file Jan 13 17:10:08 vlounge volumio[14470]: info: Listing playlists Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::getQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:08 vlounge volumio[14470]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:08 vlounge volumio[14470]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:08 vlounge volumio[14470]: info: Listing playlists Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::getQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getQueue Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:08 vlounge volumio[14470]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::pushState Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::updateTrackBlock Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrackBlock Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::pushState Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::setRandom null Jan 13 17:10:08 vlounge volumio[14470]: info: CoreStateMachine::pushState Jan 13 17:10:08 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:08 vlounge volumio[14470]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:08 vlounge volumio[14470]: info: Setting Device type: Raspberry PI Jan 13 17:10:08 vlounge volumio[14470]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:10:08 vlounge volumio[14470]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:10:08 vlounge volumio[14470]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:10:09 vlounge volumio[14470]: info: Completed loading Core Plugins Jan 13 17:10:09 vlounge volumio[14470]: info: Preparing to generate the ALSA configuration file Jan 13 17:10:09 vlounge volumio[14470]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:09 vlounge volumio[14470]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:10:09 vlounge volumio[14470]: info: Output device has changed, restarting MPD Jan 13 17:10:09 vlounge sudo[14576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:09 vlounge sudo[14576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge sudo[14576]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:09 vlounge volumio[14470]: info: Output device has changed, restarting Shairport Sync Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:09 vlounge sudo[14582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:09 vlounge sudo[14582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:10:09 vlounge volumio[14470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:09 vlounge volumio[14470]: info: ___________ START PLUGINS ___________ Jan 13 17:10:09 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:09 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:09 vlounge volumio[14470]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:10:09 vlounge volumio[14470]: info: Creating MPD Configuration file Jan 13 17:10:09 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:09 vlounge sudo[14597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:09 vlounge sudo[14597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge volumio[14470]: info: [1736788209481] CoreMusicLibrary::Adding element Media Servers Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:09 vlounge sudo[14599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:09 vlounge sudo[14599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge sudo[14597]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:09 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:10:09 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:09 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:09 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:09 vlounge volumio[14470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:09 vlounge volumio[14470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:09 vlounge volumio[14470]: info: [1736788209624] CoreMusicLibrary::Adding element Last_100 Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:09 vlounge volumio[14470]: info: [1736788209628] CoreMusicLibrary::Adding element Webradio Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:09 vlounge volumio[14470]: info: Initializing BBC Radios Jan 13 17:10:09 vlounge sudo[14605]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:10:09 vlounge sudo[14605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge sudo[14605]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:09 vlounge volumio[14470]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:10:09 vlounge volumio[14470]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:09 vlounge volumio[14470]: info: [1736788209777] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:09 vlounge volumio[14470]: Cannot find translation for source Mother Earth Radio Jan 13 17:10:09 vlounge volumio[14470]: info: Loading i18n strings for locale en Jan 13 17:10:09 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:09 vlounge volumio[14470]: info: Volumio Calling Home Jan 13 17:10:09 vlounge volumio[14470]: info: [squeezelite_mc] Proxy server started on port 41203 Jan 13 17:10:09 vlounge sudo[14625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:10:09 vlounge sudo[14625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge sudo[14627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:10:09 vlounge sudo[14627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:09 vlounge sudo[14630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:10:09 vlounge sudo[14630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:10 vlounge systemd[1]: Reloading. Jan 13 17:10:10 vlounge sudo[14625]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:10 vlounge volumio[14470]: info: [now-playing] App is listening on port 4004. Jan 13 17:10:10 vlounge volumio[14470]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:10:10 vlounge volumio[14470]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:10 vlounge volumio[14470]: info: CoreStateMachine::pushState Jan 13 17:10:10 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:10 vlounge volumio[14470]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:10:10 vlounge volumio[14470]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:10:10 vlounge volumio[14470]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:10:10 vlounge volumio[14470]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:10:10 vlounge volumio[14470]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:10:10 vlounge volumio[14470]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:10:10 vlounge volumio[14470]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:10:10 vlounge volumio[14470]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:10:10 vlounge volumio[14470]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:10:10 vlounge volumio[14470]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:10:10 vlounge volumio[14470]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:10:10 vlounge volumio[14470]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:10:10 vlounge volumio[14470]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:10:10 vlounge volumio[14470]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:10:10 vlounge volumio[14470]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:10:10 vlounge volumio[14470]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:10:10 vlounge volumio[14470]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:10:10 vlounge volumio[14470]: info: touch_display: Backlight interface detected. Jan 13 17:10:10 vlounge volumio[14470]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:10 vlounge volumio[14470]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:10:10 vlounge volumio[14470]: info: MPD Permissions set Jan 13 17:10:10 vlounge volumio[14470]: info: MPD Permissions set Jan 13 17:10:10 vlounge volumio[14470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:10:10 vlounge volumio[14470]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:10 vlounge volumio[14470]: info: CoreStateMachine::pushState Jan 13 17:10:10 vlounge volumio[14470]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:10 vlounge volumio[14470]: info: [squeezelite_mc] Server discovery started Jan 13 17:10:10 vlounge volumio[14470]: info: [squeezelite_mc] Player finder started Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:10 vlounge volumio[14470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:10 vlounge volumio[14470]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:10:10 vlounge volumio[14470]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:10 vlounge volumio[14470]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:10:10 vlounge volumio[14470]: at doSend (dgram.js:692:16) Jan 13 17:10:10 vlounge volumio[14470]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:10:10 vlounge volumio[14470]: at afterDns (dgram.js:638:5) Jan 13 17:10:10 vlounge volumio[14470]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:10:10 vlounge volumio[14470]: errno: -101, Jan 13 17:10:10 vlounge volumio[14470]: code: 'ENETUNREACH', Jan 13 17:10:10 vlounge volumio[14470]: syscall: 'send', Jan 13 17:10:10 vlounge volumio[14470]: address: '255.255.255.255', Jan 13 17:10:10 vlounge volumio[14470]: port: 3483 Jan 13 17:10:10 vlounge volumio[14470]: } Jan 13 17:10:10 vlounge volumio[14470]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:10 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:11 vlounge sudo[14630]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:11 vlounge systemd[1]: Reloading. Jan 13 17:10:11 vlounge sudo[14669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:09 Jan 13 17:10:11 vlounge sudo[14669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:11 vlounge sudo[14669]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:11 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:11 vlounge mpd[14613]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:10:11 vlounge mpd[14613]: output: No 'audio_output' defined in config file Jan 13 17:10:11 vlounge mpd[14613]: output: Successfully detected a sndio audio device Jan 13 17:10:11 vlounge mpd[14613]: zeroconf: No global port, disabling zeroconf Jan 13 17:10:12 vlounge nmbd[795]: [2025/01/13 17:10:12.192031, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Jan 13 17:10:12 vlounge nmbd[795]: reload_interfaces: No subnets to listen to. Waiting.. Jan 13 17:10:12 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:10:12 vlounge sudo[14599]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:12 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:10:12 vlounge sudo[14582]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:12 vlounge sudo[14627]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:12 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:10:12 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:12 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:12 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:10:12 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Jan 13 17:10:12 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:10:12 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:10:12 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:12 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:14 vlounge volumio[14691]: info: ------------------------------------------- Jan 13 17:10:14 vlounge volumio[14691]: info: ----- Volumio3 ---- Jan 13 17:10:14 vlounge volumio[14691]: info: ------------------------------------------- Jan 13 17:10:14 vlounge volumio[14691]: info: ----- System startup ---- Jan 13 17:10:14 vlounge volumio[14691]: info: ------------------------------------------- Jan 13 17:10:15 vlounge volumio[14691]: info: MYVOLUMIO Environment detected Jan 13 17:10:15 vlounge volumio[14691]: info: Plugin folders cleanup Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category audio_interface Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category miscellanea Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category music_service Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category plugins.json Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category system_controller Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category user_interface Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning into folder /data/plugins/ Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category music_service Jan 13 17:10:15 vlounge volumio[14691]: info: Scanning category user_interface Jan 13 17:10:15 vlounge volumio[14691]: info: Plugin folders cleanup completed Jan 13 17:10:15 vlounge volumio[14691]: info: ------------------------------------------- Jan 13 17:10:15 vlounge volumio[14691]: info: ----- Core plugins startup ---- Jan 13 17:10:15 vlounge volumio[14691]: info: ------------------------------------------- Jan 13 17:10:15 vlounge volumio[14691]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:10:15 vlounge volumio[14691]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:10:15 vlounge volumio[14691]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:10:15 vlounge volumio[14691]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:10:15 vlounge volumio[14691]: info: Loading plugins from folder /data/plugins/ Jan 13 17:10:15 vlounge volumio[14691]: info: Loading plugin "system"... Jan 13 17:10:15 vlounge volumio[14691]: info: Loading plugin "appearance"... Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "network"... Jan 13 17:10:16 vlounge volumio[14691]: info: Refreshing Cached IP Addresses Jan 13 17:10:16 vlounge sudo[14718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:10:16 vlounge sudo[14718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:16 vlounge sudo[14718]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:16 vlounge sudo[14720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:10:16 vlounge sudo[14720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "services"... Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "alsa_controller"... Jan 13 17:10:16 vlounge sudo[14720]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:16 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "wizard"... Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "networkfs"... Jan 13 17:10:16 vlounge volumio[14691]: info: Starting Udev Watcher for removable devices Jan 13 17:10:16 vlounge volumio[14691]: info: Ignoring mount for partition: boot Jan 13 17:10:16 vlounge volumio[14691]: info: Ignoring mount for partition: volumio Jan 13 17:10:16 vlounge volumio[14691]: info: Ignoring mount for partition: volumio_data Jan 13 17:10:16 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "upnp"... Jan 13 17:10:16 vlounge volumio[14691]: info: [1736788216949] Starting Upmpd Daemon Jan 13 17:10:16 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "my_music"... Jan 13 17:10:16 vlounge volumio[14691]: info: Loading plugin "mpd"... Jan 13 17:10:17 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:17] [connect] Successful connection Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "upnp_browser"... Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "alarm-clock"... Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "airplay_emulation"... Jan 13 17:10:17 vlounge volumio[14691]: info: Starting Shairport Sync Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "last_100"... Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "webradio"... Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "i2s_dacs"... Jan 13 17:10:17 vlounge volumio[14691]: info: Loading plugin "volumiodiscovery"... Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** For more information see Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:18 vlounge volumio[14691]: *** WARNING *** For more information see Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** For more information see Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:18 vlounge node[14691]: *** WARNING *** For more information see Jan 13 17:10:18 vlounge volumio[14691]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:10:18 vlounge volumio[14691]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:10:18 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:18 vlounge volumio[14691]: info: Loading plugin "squeezelite_mc"... Jan 13 17:10:19 vlounge volumio[14691]: info: Loading plugin "now_playing"... Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "outputs"... Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "albumart"... Jan 13 17:10:20 vlounge volumio[14691]: info: Plugin example_plugin is not enabled Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "inputs"... Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "updater_comm"... Jan 13 17:10:20 vlounge volumio[14691]: info: Plugin mpdemulation is not enabled Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "rest_api"... Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "websocket"... Jan 13 17:10:20 vlounge volumio[14691]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:10:20 vlounge volumio[14691]: info: Loading plugin "motherearthradio"... Jan 13 17:10:21 vlounge volumio[14691]: Forking 3 albumart workers Jan 13 17:10:21 vlounge volumio[14691]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:10:21 vlounge volumio[14691]: info: [1736788221287] [MotherEarth] API delay: 5 Jan 13 17:10:21 vlounge volumio[14691]: info: Loading plugin "touch_display"... Jan 13 17:10:21 vlounge volumio[14691]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:10:21 vlounge volumio[14691]: info: Loading i18n strings for locale en Jan 13 17:10:21 vlounge volumio[14691]: Updating browse sources language Jan 13 17:10:21 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::initPlayerControls Jan 13 17:10:22 vlounge volumio[14691]: Starting albumart workers Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:22 vlounge volumio[14691]: Express server listening on port 3000 Jan 13 17:10:22 vlounge volumio[14691]: [Metrics] WebUI: 8s 391.66ms Jan 13 17:10:22 vlounge volumio[14691]: info: CoreStateMachine::resetVolumioState Jan 13 17:10:22 vlounge volumio[14691]: info: CoreStateMachine::getcurrentVolume Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:22 vlounge volumio[14691]: Starting albumart workers Jan 13 17:10:22 vlounge volumio[14691]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:10:22 vlounge volumio[14691]: Starting albumart workers Jan 13 17:10:22 vlounge volumio[14691]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:10:22 vlounge volumio[14691]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:22 vlounge volumio[14691]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:22 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:22 vlounge volumio[14691]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:22 vlounge volumio[14691]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: Cannot read play queue from file Jan 13 17:10:23 vlounge volumio[14691]: info: Listing playlists Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::getQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:23 vlounge volumio[14691]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:23 vlounge volumio[14691]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:23 vlounge volumio[14691]: info: Listing playlists Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::getQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getQueue Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:23 vlounge volumio[14691]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::pushState Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::updateTrackBlock Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrackBlock Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::pushState Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::setRandom null Jan 13 17:10:23 vlounge volumio[14691]: info: CoreStateMachine::pushState Jan 13 17:10:23 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:23 vlounge volumio[14691]: info: Setting Device type: Raspberry PI Jan 13 17:10:23 vlounge volumio[14691]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:10:23 vlounge volumio[14691]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:10:23 vlounge volumio[14691]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:10:23 vlounge volumio[14691]: info: Completed loading Core Plugins Jan 13 17:10:23 vlounge volumio[14691]: info: Preparing to generate the ALSA configuration file Jan 13 17:10:23 vlounge volumio[14691]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:23 vlounge volumio[14691]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:10:23 vlounge volumio[14691]: info: Output device has changed, restarting MPD Jan 13 17:10:23 vlounge volumio[14691]: info: Output device has changed, restarting Shairport Sync Jan 13 17:10:23 vlounge sudo[14809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:23 vlounge sudo[14809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:23 vlounge sudo[14809]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:23 vlounge sudo[14811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:23 vlounge sudo[14811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:23 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:10:23 vlounge volumio[14691]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:23 vlounge volumio[14691]: info: ___________ START PLUGINS ___________ Jan 13 17:10:23 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:23 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:23 vlounge volumio[14691]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:10:23 vlounge volumio[14691]: info: Creating MPD Configuration file Jan 13 17:10:23 vlounge sudo[14819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:23 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:23 vlounge sudo[14819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:23 vlounge volumio[14691]: info: [1736788223709] CoreMusicLibrary::Adding element Media Servers Jan 13 17:10:23 vlounge sudo[14819]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge sudo[14821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:23 vlounge sudo[14821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:23 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:10:23 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:23 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:23 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:23 vlounge volumio[14691]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:23 vlounge volumio[14691]: info: [1736788223847] CoreMusicLibrary::Adding element Last_100 Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:23 vlounge volumio[14691]: info: [1736788223851] CoreMusicLibrary::Adding element Webradio Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:23 vlounge volumio[14691]: info: Initializing BBC Radios Jan 13 17:10:23 vlounge sudo[14827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:10:23 vlounge sudo[14827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:23 vlounge sudo[14827]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:23 vlounge volumio[14691]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:10:23 vlounge volumio[14691]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:23 vlounge volumio[14691]: info: [1736788223985] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:10:23 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:23 vlounge volumio[14691]: Cannot find translation for source Mother Earth Radio Jan 13 17:10:23 vlounge volumio[14691]: info: Loading i18n strings for locale en Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:24 vlounge volumio[14691]: info: Volumio Calling Home Jan 13 17:10:24 vlounge volumio[14691]: info: [squeezelite_mc] Proxy server started on port 34327 Jan 13 17:10:24 vlounge sudo[14847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:10:24 vlounge sudo[14847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:24 vlounge sudo[14849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:10:24 vlounge sudo[14849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:24 vlounge sudo[14852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:10:24 vlounge sudo[14852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:24 vlounge sudo[14847]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:24 vlounge systemd[1]: Reloading. Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:24 vlounge volumio[14691]: info: [now-playing] App is listening on port 4004. Jan 13 17:10:24 vlounge volumio[14691]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:10:24 vlounge volumio[14691]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:24 vlounge volumio[14691]: info: CoreStateMachine::pushState Jan 13 17:10:24 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:24 vlounge volumio[14691]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:10:24 vlounge volumio[14691]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:10:24 vlounge volumio[14691]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:10:24 vlounge volumio[14691]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:10:24 vlounge volumio[14691]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:10:24 vlounge volumio[14691]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:10:24 vlounge volumio[14691]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:10:24 vlounge volumio[14691]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:10:24 vlounge volumio[14691]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:10:24 vlounge volumio[14691]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:10:24 vlounge volumio[14691]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:10:24 vlounge volumio[14691]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:10:24 vlounge volumio[14691]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:10:24 vlounge volumio[14691]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:10:24 vlounge volumio[14691]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:10:24 vlounge volumio[14691]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:10:24 vlounge volumio[14691]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:10:24 vlounge volumio[14691]: info: touch_display: Backlight interface detected. Jan 13 17:10:24 vlounge volumio[14691]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:24 vlounge volumio[14691]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:10:24 vlounge volumio[14691]: info: MPD Permissions set Jan 13 17:10:24 vlounge volumio[14691]: info: MPD Permissions set Jan 13 17:10:24 vlounge volumio[14691]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:10:24 vlounge volumio[14691]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:24 vlounge volumio[14691]: info: CoreStateMachine::pushState Jan 13 17:10:24 vlounge volumio[14691]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:24 vlounge volumio[14691]: info: [squeezelite_mc] Server discovery started Jan 13 17:10:24 vlounge volumio[14691]: info: [squeezelite_mc] Player finder started Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:24 vlounge volumio[14691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:24 vlounge volumio[14691]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:10:24 vlounge volumio[14691]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:24 vlounge volumio[14691]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:10:24 vlounge volumio[14691]: at doSend (dgram.js:692:16) Jan 13 17:10:24 vlounge volumio[14691]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:10:24 vlounge volumio[14691]: at afterDns (dgram.js:638:5) Jan 13 17:10:24 vlounge volumio[14691]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:10:24 vlounge volumio[14691]: errno: -101, Jan 13 17:10:24 vlounge volumio[14691]: code: 'ENETUNREACH', Jan 13 17:10:24 vlounge volumio[14691]: syscall: 'send', Jan 13 17:10:24 vlounge volumio[14691]: address: '255.255.255.255', Jan 13 17:10:24 vlounge volumio[14691]: port: 3483 Jan 13 17:10:24 vlounge volumio[14691]: } Jan 13 17:10:24 vlounge volumio[14691]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:24 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:25 vlounge systemd[1]: Reloading. Jan 13 17:10:25 vlounge sudo[14849]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:25 vlounge sudo[14890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:09 Jan 13 17:10:25 vlounge sudo[14890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:25 vlounge sudo[14890]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:25 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:25 vlounge mpd[14835]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:10:25 vlounge mpd[14835]: output: No 'audio_output' defined in config file Jan 13 17:10:25 vlounge mpd[14835]: output: Successfully detected a sndio audio device Jan 13 17:10:25 vlounge mpd[14835]: zeroconf: No global port, disabling zeroconf Jan 13 17:10:26 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:10:26 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:10:26 vlounge sudo[14811]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:26 vlounge sudo[14821]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:26 vlounge sudo[14852]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:26 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:10:26 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:26 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:26 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:10:26 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Jan 13 17:10:26 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:10:26 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:10:26 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:26 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:28 vlounge volumio[14950]: info: ------------------------------------------- Jan 13 17:10:28 vlounge volumio[14950]: info: ----- Volumio3 ---- Jan 13 17:10:28 vlounge volumio[14950]: info: ------------------------------------------- Jan 13 17:10:28 vlounge volumio[14950]: info: ----- System startup ---- Jan 13 17:10:28 vlounge volumio[14950]: info: ------------------------------------------- Jan 13 17:10:29 vlounge volumio[14950]: info: MYVOLUMIO Environment detected Jan 13 17:10:29 vlounge volumio[14950]: info: Plugin folders cleanup Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category audio_interface Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category miscellanea Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category music_service Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category plugins.json Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category system_controller Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category user_interface Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning into folder /data/plugins/ Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category music_service Jan 13 17:10:29 vlounge volumio[14950]: info: Scanning category user_interface Jan 13 17:10:29 vlounge volumio[14950]: info: Plugin folders cleanup completed Jan 13 17:10:29 vlounge volumio[14950]: info: ------------------------------------------- Jan 13 17:10:29 vlounge volumio[14950]: info: ----- Core plugins startup ---- Jan 13 17:10:29 vlounge volumio[14950]: info: ------------------------------------------- Jan 13 17:10:29 vlounge volumio[14950]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:10:29 vlounge volumio[14950]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:10:29 vlounge volumio[14950]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:10:29 vlounge volumio[14950]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:10:29 vlounge volumio[14950]: info: Loading plugins from folder /data/plugins/ Jan 13 17:10:29 vlounge volumio[14950]: info: Loading plugin "system"... Jan 13 17:10:29 vlounge volumio[14950]: info: Loading plugin "appearance"... Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "network"... Jan 13 17:10:30 vlounge volumio[14950]: info: Refreshing Cached IP Addresses Jan 13 17:10:30 vlounge sudo[14977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:10:30 vlounge sudo[14977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:30 vlounge sudo[14977]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "services"... Jan 13 17:10:30 vlounge sudo[14979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "alsa_controller"... Jan 13 17:10:30 vlounge sudo[14979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:30 vlounge sudo[14979]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:30 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "wizard"... Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "networkfs"... Jan 13 17:10:30 vlounge volumio[14950]: info: Starting Udev Watcher for removable devices Jan 13 17:10:30 vlounge volumio[14950]: info: Ignoring mount for partition: boot Jan 13 17:10:30 vlounge volumio[14950]: info: Ignoring mount for partition: volumio Jan 13 17:10:30 vlounge volumio[14950]: info: Ignoring mount for partition: volumio_data Jan 13 17:10:30 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "upnp"... Jan 13 17:10:30 vlounge volumio[14950]: info: [1736788230859] Starting Upmpd Daemon Jan 13 17:10:30 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "my_music"... Jan 13 17:10:30 vlounge volumio[14950]: info: Loading plugin "mpd"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "upnp_browser"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "alarm-clock"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "airplay_emulation"... Jan 13 17:10:31 vlounge volumio[14950]: info: Starting Shairport Sync Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "last_100"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "webradio"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "i2s_dacs"... Jan 13 17:10:31 vlounge volumio[14950]: info: Loading plugin "volumiodiscovery"... Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** For more information see Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:31 vlounge volumio[14950]: *** WARNING *** For more information see Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** For more information see Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:31 vlounge node[14950]: *** WARNING *** For more information see Jan 13 17:10:31 vlounge volumio[14950]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:10:31 vlounge volumio[14950]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:10:32 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:32 vlounge volumio[14950]: info: Loading plugin "squeezelite_mc"... Jan 13 17:10:32 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:32] [connect] Successful connection Jan 13 17:10:32 vlounge volumio[14950]: info: Loading plugin "now_playing"... Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "outputs"... Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "albumart"... Jan 13 17:10:34 vlounge volumio[14950]: info: Plugin example_plugin is not enabled Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "inputs"... Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "updater_comm"... Jan 13 17:10:34 vlounge volumio[14950]: info: Plugin mpdemulation is not enabled Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "rest_api"... Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "websocket"... Jan 13 17:10:34 vlounge volumio[14950]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "motherearthradio"... Jan 13 17:10:34 vlounge volumio[14950]: Forking 3 albumart workers Jan 13 17:10:34 vlounge volumio[14950]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:10:34 vlounge volumio[14950]: info: [1736788234960] [MotherEarth] API delay: 5 Jan 13 17:10:34 vlounge volumio[14950]: info: Loading plugin "touch_display"... Jan 13 17:10:35 vlounge volumio[14950]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:10:35 vlounge volumio[14950]: info: Loading i18n strings for locale en Jan 13 17:10:35 vlounge volumio[14950]: Updating browse sources language Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::initPlayerControls Jan 13 17:10:35 vlounge volumio[14950]: Starting albumart workers Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:35 vlounge volumio[14950]: Express server listening on port 3000 Jan 13 17:10:35 vlounge volumio[14950]: [Metrics] WebUI: 8s 25.24ms Jan 13 17:10:36 vlounge volumio[14950]: Starting albumart workers Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::resetVolumioState Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::getcurrentVolume Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:36 vlounge volumio[14950]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:10:36 vlounge volumio[14950]: Starting albumart workers Jan 13 17:10:36 vlounge volumio[14950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:10:36 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736788232 101 Jan 13 17:10:36 vlounge volumio[14950]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 13 17:10:36 vlounge volumio[14950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:36 vlounge volumio[14950]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:36 vlounge volumio[14950]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:36 vlounge volumio[14950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:36 vlounge volumio[14950]: info: Cannot read play queue from file Jan 13 17:10:36 vlounge volumio[14950]: info: Listing playlists Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::getQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:36 vlounge volumio[14950]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:36 vlounge volumio[14950]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:36 vlounge volumio[14950]: info: Listing playlists Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::getQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getQueue Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:36 vlounge volumio[14950]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::pushState Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::updateTrackBlock Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrackBlock Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:36 vlounge volumio[14950]: info: Setting Device type: Raspberry PI Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::pushState Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::setRandom null Jan 13 17:10:36 vlounge volumio[14950]: info: CoreStateMachine::pushState Jan 13 17:10:36 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:36 vlounge volumio[14950]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:36 vlounge volumio[14950]: info: Completed loading Core Plugins Jan 13 17:10:36 vlounge volumio[14950]: info: Preparing to generate the ALSA configuration file Jan 13 17:10:36 vlounge volumio[14950]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:10:36 vlounge volumio[14950]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:10:36 vlounge volumio[14950]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:10:36 vlounge volumio[14950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:37 vlounge volumio[14950]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:10:37 vlounge volumio[14950]: info: Output device has changed, restarting MPD Jan 13 17:10:37 vlounge sudo[15060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:37 vlounge sudo[15060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge volumio[14950]: info: Output device has changed, restarting Shairport Sync Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:37 vlounge sudo[15060]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:37 vlounge sudo[15066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:37 vlounge sudo[15066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:10:37 vlounge volumio[14950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:37 vlounge volumio[14950]: info: ___________ START PLUGINS ___________ Jan 13 17:10:37 vlounge volumio[14950]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:10:37 vlounge volumio[14950]: info: Creating MPD Configuration file Jan 13 17:10:37 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:37 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:37 vlounge sudo[15078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:37 vlounge sudo[15078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:37 vlounge volumio[14950]: info: [1736788237284] CoreMusicLibrary::Adding element Media Servers Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:37 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:37 vlounge sudo[15078]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:37 vlounge sudo[15080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:37 vlounge sudo[15080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:37 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:10:37 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:37 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:37 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:37 vlounge volumio[14950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:37 vlounge volumio[14950]: info: [1736788237440] CoreMusicLibrary::Adding element Last_100 Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:37 vlounge volumio[14950]: info: [1736788237444] CoreMusicLibrary::Adding element Webradio Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:37 vlounge volumio[14950]: info: Initializing BBC Radios Jan 13 17:10:37 vlounge sudo[15084]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:10:37 vlounge sudo[15084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge sudo[15084]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:37 vlounge volumio[14950]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:10:37 vlounge volumio[14950]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:37 vlounge volumio[14950]: info: [1736788237581] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:37 vlounge volumio[14950]: Cannot find translation for source Mother Earth Radio Jan 13 17:10:37 vlounge volumio[14950]: info: Loading i18n strings for locale en Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:37 vlounge volumio[14950]: info: Volumio Calling Home Jan 13 17:10:37 vlounge volumio[14950]: info: [squeezelite_mc] Proxy server started on port 39821 Jan 13 17:10:37 vlounge sudo[15106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:10:37 vlounge sudo[15106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge sudo[15108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:10:37 vlounge sudo[15108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge sudo[15111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:10:37 vlounge sudo[15111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:37 vlounge systemd[1]: Reloading. Jan 13 17:10:37 vlounge sudo[15106]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:37 vlounge volumio[14950]: info: [now-playing] App is listening on port 4004. Jan 13 17:10:37 vlounge volumio[14950]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:10:37 vlounge volumio[14950]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:37 vlounge volumio[14950]: info: CoreStateMachine::pushState Jan 13 17:10:37 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:37 vlounge volumio[14950]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:37 vlounge volumio[14950]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:10:37 vlounge volumio[14950]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:10:37 vlounge volumio[14950]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:10:37 vlounge volumio[14950]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:10:37 vlounge volumio[14950]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:10:37 vlounge volumio[14950]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:10:37 vlounge volumio[14950]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:10:37 vlounge volumio[14950]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:10:37 vlounge volumio[14950]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:10:37 vlounge volumio[14950]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:10:37 vlounge volumio[14950]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:10:37 vlounge volumio[14950]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:10:37 vlounge volumio[14950]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:10:37 vlounge volumio[14950]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:10:37 vlounge volumio[14950]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:10:37 vlounge volumio[14950]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:10:37 vlounge volumio[14950]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:10:38 vlounge volumio[14950]: info: touch_display: Backlight interface detected. Jan 13 17:10:38 vlounge volumio[14950]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:38 vlounge volumio[14950]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:10:38 vlounge volumio[14950]: info: MPD Permissions set Jan 13 17:10:38 vlounge volumio[14950]: info: MPD Permissions set Jan 13 17:10:38 vlounge volumio[14950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:10:38 vlounge volumio[14950]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:38 vlounge volumio[14950]: info: CoreStateMachine::pushState Jan 13 17:10:38 vlounge volumio[14950]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:38 vlounge volumio[14950]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:38 vlounge volumio[14950]: info: [squeezelite_mc] Server discovery started Jan 13 17:10:38 vlounge volumio[14950]: info: [squeezelite_mc] Player finder started Jan 13 17:10:38 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:38 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:38 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:38 vlounge volumio[14950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:38 vlounge volumio[14950]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:10:38 vlounge volumio[14950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:38 vlounge volumio[14950]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:10:38 vlounge volumio[14950]: at doSend (dgram.js:692:16) Jan 13 17:10:38 vlounge volumio[14950]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:10:38 vlounge volumio[14950]: at afterDns (dgram.js:638:5) Jan 13 17:10:38 vlounge volumio[14950]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:10:38 vlounge volumio[14950]: errno: -101, Jan 13 17:10:38 vlounge volumio[14950]: code: 'ENETUNREACH', Jan 13 17:10:38 vlounge volumio[14950]: syscall: 'send', Jan 13 17:10:38 vlounge volumio[14950]: address: '255.255.255.255', Jan 13 17:10:38 vlounge volumio[14950]: port: 3483 Jan 13 17:10:38 vlounge volumio[14950]: } Jan 13 17:10:38 vlounge volumio[14950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:38 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:38 vlounge sudo[15108]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:38 vlounge systemd[1]: Reloading. Jan 13 17:10:39 vlounge sudo[15140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:09 Jan 13 17:10:39 vlounge sudo[15140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:39 vlounge sudo[15140]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:39 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:39 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 17:10:39 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 17:10:39 vlounge mpd[15093]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:10:39 vlounge mpd[15093]: output: No 'audio_output' defined in config file Jan 13 17:10:39 vlounge mpd[15093]: output: Successfully detected a sndio audio device Jan 13 17:10:39 vlounge mpd[15093]: zeroconf: No global port, disabling zeroconf Jan 13 17:10:39 vlounge systemd[1]: Started Music Player Daemon. Jan 13 17:10:39 vlounge sudo[15111]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:39 vlounge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:10:39 vlounge sudo[15066]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:39 vlounge sudo[15080]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:40 vlounge systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:10:40 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:40 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:40 vlounge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:10:40 vlounge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Jan 13 17:10:40 vlounge systemd[1]: Started dynamicswap service. Jan 13 17:10:40 vlounge systemd[1]: Stopped Volumio Backend Module. Jan 13 17:10:40 vlounge systemd[1]: Started Volumio Backend Module. Jan 13 17:10:40 vlounge systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:10:41 vlounge kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jan 13 17:10:41 vlounge ifplugd(eth0)[813]: Link beat detected. Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 13 17:10:42 vlounge dhcpcd[15208]: DUID 00:01:00:01:2d:f2:1d:9b:b8:27:eb:24:69:78 Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: client: DUID 00:01:00:01:2d:f2:1d:9b:b8:27:eb:24:69:78 Jan 13 17:10:42 vlounge dhcpcd[15208]: eth0: IAID eb:98:5f:f8 Jan 13 17:10:42 vlounge dhcpcd[15208]: eth0: adding address fe80::ba27:ebff:fe98:5ff8 Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: client: eth0: IAID eb:98:5f:f8 Jan 13 17:10:42 vlounge dhcpcd[15208]: ipv6_addaddr1: Permission denied Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: client: eth0: adding address fe80::ba27:ebff:fe98:5ff8 Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: client: ipv6_addaddr1: Permission denied Jan 13 17:10:42 vlounge volumio[15169]: info: ------------------------------------------- Jan 13 17:10:42 vlounge volumio[15169]: info: ----- Volumio3 ---- Jan 13 17:10:42 vlounge volumio[15169]: info: ------------------------------------------- Jan 13 17:10:42 vlounge volumio[15169]: info: ----- System startup ---- Jan 13 17:10:42 vlounge volumio[15169]: info: ------------------------------------------- Jan 13 17:10:42 vlounge dhcpcd[15208]: eth0: rebinding lease of 192.168.111.129 Jan 13 17:10:42 vlounge ifplugd(eth0)[813]: client: eth0: rebinding lease of 192.168.111.129 Jan 13 17:10:43 vlounge dhcpcd[15208]: eth0: soliciting an IPv6 router Jan 13 17:10:43 vlounge ifplugd(eth0)[813]: client: eth0: soliciting an IPv6 router Jan 13 17:10:43 vlounge volumio[15169]: info: MYVOLUMIO Environment detected Jan 13 17:10:43 vlounge volumio[15169]: info: Plugin folders cleanup Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category audio_interface Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category miscellanea Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category music_service Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category plugins.json Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category system_controller Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category user_interface Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning into folder /data/plugins/ Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category music_service Jan 13 17:10:43 vlounge volumio[15169]: info: Scanning category user_interface Jan 13 17:10:43 vlounge volumio[15169]: info: Plugin folders cleanup completed Jan 13 17:10:43 vlounge volumio[15169]: info: ------------------------------------------- Jan 13 17:10:43 vlounge volumio[15169]: info: ----- Core plugins startup ---- Jan 13 17:10:43 vlounge volumio[15169]: info: ------------------------------------------- Jan 13 17:10:43 vlounge volumio[15169]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:10:43 vlounge volumio[15169]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:10:43 vlounge volumio[15169]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:10:43 vlounge volumio[15169]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:10:43 vlounge volumio[15169]: info: Loading plugins from folder /data/plugins/ Jan 13 17:10:43 vlounge volumio[15169]: info: Loading plugin "system"... Jan 13 17:10:43 vlounge volumio[15169]: info: Loading plugin "appearance"... Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "network"... Jan 13 17:10:44 vlounge volumio[15169]: info: Refreshing Cached IP Addresses Jan 13 17:10:44 vlounge volumio-remote-updater[600]: [2025-01-13 17:10:44] [connect] Successful connection Jan 13 17:10:44 vlounge sudo[15220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:10:44 vlounge sudo[15220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:44 vlounge sudo[15220]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "services"... Jan 13 17:10:44 vlounge sudo[15222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:10:44 vlounge sudo[15222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "alsa_controller"... Jan 13 17:10:44 vlounge sudo[15222]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:44 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "wizard"... Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "networkfs"... Jan 13 17:10:44 vlounge volumio[15169]: info: Starting Udev Watcher for removable devices Jan 13 17:10:44 vlounge volumio[15169]: info: Ignoring mount for partition: boot Jan 13 17:10:44 vlounge volumio[15169]: info: Ignoring mount for partition: volumio Jan 13 17:10:44 vlounge volumio[15169]: info: Ignoring mount for partition: volumio_data Jan 13 17:10:44 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "upnp"... Jan 13 17:10:44 vlounge volumio[15169]: info: [1736788244688] Starting Upmpd Daemon Jan 13 17:10:44 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "my_music"... Jan 13 17:10:44 vlounge volumio[15169]: info: Loading plugin "mpd"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "upnp_browser"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "alarm-clock"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "airplay_emulation"... Jan 13 17:10:45 vlounge volumio[15169]: info: Starting Shairport Sync Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "last_100"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "webradio"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "i2s_dacs"... Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "volumiodiscovery"... Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** For more information see Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:45 vlounge volumio[15169]: *** WARNING *** For more information see Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** For more information see Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:10:45 vlounge node[15169]: *** WARNING *** For more information see Jan 13 17:10:45 vlounge volumio[15169]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:10:45 vlounge volumio[15169]: info: Discovery: Started advertising with name: Vlounge Jan 13 17:10:45 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:10:45 vlounge volumio[15169]: info: Loading plugin "squeezelite_mc"... Jan 13 17:10:46 vlounge volumio[15169]: info: Loading plugin "now_playing"... Jan 13 17:10:47 vlounge dhcpcd[15208]: eth0: probing for an IPv4LL address Jan 13 17:10:47 vlounge ifplugd(eth0)[813]: client: eth0: probing for an IPv4LL address Jan 13 17:10:47 vlounge dhcpcd[15208]: eth0: DHCP lease expired Jan 13 17:10:47 vlounge ifplugd(eth0)[813]: client: eth0: DHCP lease expired Jan 13 17:10:47 vlounge dhcpcd[15208]: eth0: soliciting a DHCP lease Jan 13 17:10:47 vlounge ifplugd(eth0)[813]: client: eth0: soliciting a DHCP lease Jan 13 17:10:47 vlounge dhcpcd[15208]: eth0: offered 192.168.111.129 from 192.168.111.1 Jan 13 17:10:47 vlounge ifplugd(eth0)[813]: client: eth0: offered 192.168.111.129 from 192.168.111.1 Jan 13 17:10:47 vlounge dhcpcd[15208]: eth0: probing address 192.168.111.129/24 Jan 13 17:10:47 vlounge ifplugd(eth0)[813]: client: eth0: probing address 192.168.111.129/24 Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "outputs"... Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "albumart"... Jan 13 17:10:48 vlounge volumio[15169]: info: Plugin example_plugin is not enabled Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "inputs"... Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "updater_comm"... Jan 13 17:10:48 vlounge volumio[15169]: info: Plugin mpdemulation is not enabled Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "rest_api"... Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "websocket"... Jan 13 17:10:48 vlounge volumio[15169]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "motherearthradio"... Jan 13 17:10:48 vlounge volumio[15169]: Forking 3 albumart workers Jan 13 17:10:48 vlounge volumio[15169]: info: Applying required configuration parameters for plugin motherearthradio Jan 13 17:10:48 vlounge volumio[15169]: info: [1736788248735] [MotherEarth] API delay: 5 Jan 13 17:10:48 vlounge volumio[15169]: info: Loading plugin "touch_display"... Jan 13 17:10:49 vlounge volumio[15169]: info: Applying required configuration parameters for plugin touch_display Jan 13 17:10:49 vlounge volumio[15169]: info: Loading i18n strings for locale en Jan 13 17:10:49 vlounge volumio[15169]: Updating browse sources language Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::initPlayerControls Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:49 vlounge volumio[15169]: Express server listening on port 3000 Jan 13 17:10:49 vlounge volumio[15169]: [Metrics] WebUI: 7s 977.77ms Jan 13 17:10:49 vlounge volumio[15169]: info: CoreStateMachine::resetVolumioState Jan 13 17:10:49 vlounge volumio[15169]: info: CoreStateMachine::getcurrentVolume Jan 13 17:10:49 vlounge volumio[15169]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:49 vlounge volumio[15169]: Starting albumart workers Jan 13 17:10:49 vlounge volumio[15169]: Starting albumart workers Jan 13 17:10:49 vlounge volumio[15169]: Starting albumart workers Jan 13 17:10:49 vlounge volumio[15169]: info: Volumio Network Manager: Network status updated: 0 Jan 13 17:10:50 vlounge volumio[15169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:10:50 vlounge volumio[15169]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:50 vlounge volumio[15169]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:50 vlounge volumio[15169]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:50 vlounge volumio[15169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:50 vlounge volumio[15169]: info: Cannot read play queue from file Jan 13 17:10:50 vlounge volumio[15169]: info: Listing playlists Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::getQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 13 17:10:50 vlounge volumio[15169]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Jan 13 17:10:50 vlounge volumio[15169]: info: Listing playlists Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::getQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getQueue Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioGetState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::pushState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::updateTrackBlock Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrackBlock Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::pushState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::setRandom null Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::pushState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:50 vlounge volumio[15169]: info: Setting Device type: Raspberry PI Jan 13 17:10:50 vlounge volumio[15169]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 13 17:10:50 vlounge volumio[15169]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 13 17:10:50 vlounge volumio[15169]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 13 17:10:50 vlounge volumio[15169]: info: Completed loading Core Plugins Jan 13 17:10:50 vlounge volumio[15169]: info: Preparing to generate the ALSA configuration file Jan 13 17:10:50 vlounge volumio[15169]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:50 vlounge volumio[15169]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:50 vlounge volumio[15169]: info: CoreStateMachine::pushState Jan 13 17:10:50 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:50 vlounge volumio[15169]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:10:50 vlounge volumio[15169]: info: Output device has changed, restarting MPD Jan 13 17:10:50 vlounge volumio[15169]: info: Output device has changed, restarting Shairport Sync Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:50 vlounge sudo[15304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:50 vlounge sudo[15304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:50 vlounge sudo[15302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:50 vlounge sudo[15302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:50 vlounge sudo[15302]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:50 vlounge systemd[1]: Stopping Music Player Daemon... Jan 13 17:10:50 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:50 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:50 vlounge volumio[15169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:50 vlounge volumio[15169]: info: ___________ START PLUGINS ___________ Jan 13 17:10:50 vlounge volumio[15169]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:10:50 vlounge volumio[15169]: info: Creating MPD Configuration file Jan 13 17:10:50 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:50 vlounge sudo[15320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:10:50 vlounge sudo[15320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:50 vlounge sudo[15320]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:50 vlounge volumio[15169]: info: [1736788250975] CoreMusicLibrary::Adding element Media Servers Jan 13 17:10:50 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:50 vlounge sudo[15327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:10:51 vlounge sudo[15327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:51 vlounge systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:10:51 vlounge systemd[1]: mpd.service: Succeeded. Jan 13 17:10:51 vlounge systemd[1]: Stopped Music Player Daemon. Jan 13 17:10:51 vlounge volumio[15169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:51 vlounge systemd[1]: Starting Music Player Daemon... Jan 13 17:10:51 vlounge volumio[15169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:51 vlounge volumio[15169]: info: [1736788251141] CoreMusicLibrary::Adding element Last_100 Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:51 vlounge volumio[15169]: info: [1736788251145] CoreMusicLibrary::Adding element Webradio Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:51 vlounge volumio[15169]: info: Initializing BBC Radios Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:51 vlounge sudo[15334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:10:51 vlounge sudo[15334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:51 vlounge sudo[15334]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:51 vlounge volumio[15169]: info: [squeezelite_mc] Starting proxy server... Jan 13 17:10:51 vlounge volumio[15169]: info: [now-playing] ConfigUpdater: config is up to date. Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:10:51 vlounge volumio[15169]: info: [1736788251284] CoreMusicLibrary::Adding element Mother Earth Radio Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:10:51 vlounge volumio[15169]: Cannot find translation for source Mother Earth Radio Jan 13 17:10:51 vlounge volumio[15169]: info: Loading i18n strings for locale en Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:10:51 vlounge volumio[15169]: info: Volumio Calling Home Jan 13 17:10:51 vlounge volumio[15169]: info: [squeezelite_mc] Proxy server started on port 33019 Jan 13 17:10:51 vlounge sudo[15357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 13 17:10:51 vlounge sudo[15357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:51 vlounge sudo[15360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 13 17:10:51 vlounge sudo[15360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:51 vlounge sudo[15362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 17:10:51 vlounge sudo[15362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:51 vlounge sudo[15357]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:51 vlounge systemd[1]: Reloading. Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:10:51 vlounge volumio[15169]: info: [now-playing] App is listening on port 4004. Jan 13 17:10:51 vlounge volumio[15169]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 13 17:10:51 vlounge volumio[15169]: Unhandled rejection Error: No sockets available, cannot start. Jan 13 17:10:51 vlounge volumio[15169]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 13 17:10:51 vlounge volumio[15169]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 13 17:10:51 vlounge volumio[15169]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 13 17:10:51 vlounge volumio[15169]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 13 17:10:51 vlounge volumio[15169]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 13 17:10:51 vlounge volumio[15169]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 13 17:10:51 vlounge volumio[15169]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 13 17:10:51 vlounge volumio[15169]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 13 17:10:51 vlounge volumio[15169]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 13 17:10:51 vlounge volumio[15169]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 13 17:10:51 vlounge volumio[15169]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 13 17:10:51 vlounge volumio[15169]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 13 17:10:51 vlounge volumio[15169]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 13 17:10:51 vlounge volumio[15169]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 13 17:10:51 vlounge volumio[15169]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 13 17:10:51 vlounge volumio[15169]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 13 17:10:51 vlounge volumio[15169]: info: touch_display: Backlight interface detected. Jan 13 17:10:51 vlounge volumio[15169]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 13 17:10:51 vlounge volumio[15169]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 13 17:10:51 vlounge volumio[15169]: info: MPD Permissions set Jan 13 17:10:51 vlounge volumio[15169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:51 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 17:10:52 vlounge volumio[15169]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 13 17:10:52 vlounge volumio[15169]: info: VolumeController:: Volume=undefined Mute =false Jan 13 17:10:52 vlounge volumio[15169]: info: CoreStateMachine::pushState Jan 13 17:10:52 vlounge volumio[15169]: info: CorePlayQueue::getTrack 0 Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::volumioPushState Jan 13 17:10:52 vlounge sudo[15390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 13 17:10:52 vlounge sudo[15390]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:52 vlounge sudo[15390]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:52 vlounge volumio[15169]: info: [squeezelite_mc] Server discovery started Jan 13 17:10:52 vlounge volumio[15169]: info: [squeezelite_mc] Player finder started Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:10:52 vlounge volumio[15169]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 13 17:10:52 vlounge volumio[15169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:52 vlounge volumio[15169]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 13 17:10:52 vlounge volumio[15169]: at doSend (dgram.js:692:16) Jan 13 17:10:52 vlounge volumio[15169]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 13 17:10:52 vlounge volumio[15169]: at afterDns (dgram.js:638:5) Jan 13 17:10:52 vlounge volumio[15169]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 13 17:10:52 vlounge volumio[15169]: errno: -101, Jan 13 17:10:52 vlounge volumio[15169]: code: 'ENETUNREACH', Jan 13 17:10:52 vlounge volumio[15169]: syscall: 'send', Jan 13 17:10:52 vlounge volumio[15169]: address: '255.255.255.255', Jan 13 17:10:52 vlounge volumio[15169]: port: 3483 Jan 13 17:10:52 vlounge volumio[15169]: } Jan 13 17:10:52 vlounge volumio[15169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:10:52 vlounge dhcpcd[15208]: eth0: using IPv4LL address 169.254.89.183 Jan 13 17:10:52 vlounge avahi-daemon[584]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.89.183. Jan 13 17:10:52 vlounge avahi-daemon[584]: New relevant interface eth0.IPv4 for mDNS. Jan 13 17:10:52 vlounge avahi-daemon[584]: Registering new address record for 169.254.89.183 on eth0.IPv4. Jan 13 17:10:52 vlounge dhcpcd[15208]: eth0: adding route to 169.254.0.0/16 Jan 13 17:10:52 vlounge ifplugd(eth0)[813]: client: eth0: using IPv4LL address 169.254.89.183 Jan 13 17:10:52 vlounge ifplugd(eth0)[813]: client: eth0: adding route to 169.254.0.0/16 Jan 13 17:10:52 vlounge ifplugd(eth0)[813]: client: eth0: adding default route Jan 13 17:10:52 vlounge dhcpcd[15208]: eth0: adding default route Jan 13 17:10:52 vlounge dhcpcd[15208]: forked to background, child pid 15415 Jan 13 17:10:52 vlounge ifplugd(eth0)[813]: client: forked to background, child pid 15415 Jan 13 17:10:52 vlounge systemd[1]: Reloading. Jan 13 17:10:52 vlounge sudo[15362]: pam_unix(sudo:session): session closed for user root Jan 13 17:10:52 vlounge dhcpcd[15415]: eth0: leased 192.168.111.129 for 7200 seconds Jan 13 17:10:52 vlounge avahi-daemon[584]: Registering new address record for 192.168.111.129 on eth0.IPv4. Jan 13 17:10:52 vlounge dhcpcd[15415]: eth0: adding route to 192.168.111.0/24 Jan 13 17:10:52 vlounge dhcpcd[15415]: eth0: changing default route via 192.168.111.1 Jan 13 17:10:53 vlounge avahi-daemon[584]: Withdrawing address record for 169.254.89.183 on eth0. Jan 13 17:10:53 vlounge dhcpcd[15415]: eth0: deleting route to 169.254.0.0/16 Jan 13 17:10:53 vlounge avahi-daemon[584]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.89.183. Jan 13 17:10:53 vlounge avahi-daemon[584]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.111.129. Jan 13 17:10:53 vlounge dhcpcd[15415]: eth0: pid 15415 deleted default route via 192.168.111.1 Jan 13 17:10:53 vlounge sudo[15501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:09 Jan 13 17:10:53 vlounge sudo[15501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:10:53 vlounge systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"