-- Logs begin at Mon 2024-12-16 05:35:29 -03, end at Mon 2024-12-16 05:38:37 -03. -- Dec 16 05:37:00 dormitorio volumio[1783]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::pushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::updateTrackBlock Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrackBlock Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:00 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734338215 101 Dec 16 05:37:00 dormitorio volumio[1783]: 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: 1 Dec 16 05:37:00 dormitorio volumio[1783]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::pushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::setRandom null Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::pushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:00 dormitorio volumio[1783]: info: Setting Device type: Raspberry PI Dec 16 05:37:00 dormitorio volumio[1783]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::pushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:00 dormitorio volumio[1783]: info: Completed loading Core Plugins Dec 16 05:37:00 dormitorio volumio[1783]: info: Preparing to generate the ALSA configuration file Dec 16 05:37:00 dormitorio volumio[1783]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:37:00 dormitorio volumio[1783]: info: Output device has changed, restarting MPD Dec 16 05:37:00 dormitorio sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:00 dormitorio volumio[1783]: info: Output device has changed, restarting Shairport Sync Dec 16 05:37:00 dormitorio sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:00 dormitorio sudo[1917]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:00 dormitorio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:00 dormitorio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:00 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:37:00 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:00 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:00 dormitorio volumio[1783]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:00 dormitorio volumio[1783]: info: ___________ START PLUGINS ___________ Dec 16 05:37:00 dormitorio volumio[1783]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:37:00 dormitorio volumio[1783]: info: Creating MPD Configuration file Dec 16 05:37:00 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:00 dormitorio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:00 dormitorio volumio[1783]: info: [1734338220342] CoreMusicLibrary::Adding element Media Servers Dec 16 05:37:00 dormitorio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:00 dormitorio sudo[1927]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:00 dormitorio sudo[1929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:00 dormitorio sudo[1929]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:00 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:37:00 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:00 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:00 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:00 dormitorio volumio[1783]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:00 dormitorio volumio[1783]: info: [1734338220467] CoreMusicLibrary::Adding element Last_100 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:00 dormitorio volumio[1783]: info: [1734338220471] CoreMusicLibrary::Adding element Webradio Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:00 dormitorio volumio[1783]: info: Initializing BBC Radios Dec 16 05:37:00 dormitorio sudo[1935]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:37:00 dormitorio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:00 dormitorio sudo[1935]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: Creating Spotify config file Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:37:00 dormitorio volumio[1783]: info: Volumio Calling Home Dec 16 05:37:00 dormitorio volumio[1783]: info: [squeezelite_mc] Proxy server started on port 46091 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:00 dormitorio volumio[1783]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:37:00 dormitorio volumio[1783]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:37:00 dormitorio volumio[1783]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:37:00 dormitorio volumio[1783]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:37:00 dormitorio volumio[1783]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:37:00 dormitorio volumio[1783]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:37:00 dormitorio volumio[1783]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:37:00 dormitorio volumio[1783]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:37:00 dormitorio volumio[1783]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:37:00 dormitorio volumio[1783]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:37:00 dormitorio volumio[1783]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:37:00 dormitorio volumio[1783]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:37:00 dormitorio volumio[1783]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:37:00 dormitorio volumio[1783]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:37:00 dormitorio volumio[1783]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:37:00 dormitorio volumio[1783]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:37:00 dormitorio volumio[1783]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:37:00 dormitorio volumio[1783]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:00 dormitorio volumio[1783]: info: MPD Permissions set Dec 16 05:37:00 dormitorio volumio[1783]: info: MPD Permissions set Dec 16 05:37:00 dormitorio volumio[1783]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreStateMachine::pushState Dec 16 05:37:00 dormitorio volumio[1783]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:00 dormitorio volumio[1783]: info: [squeezelite_mc] Server discovery started Dec 16 05:37:00 dormitorio volumio[1783]: info: [squeezelite_mc] Player finder started Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:00 dormitorio volumio[1783]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:37:00 dormitorio volumio[1783]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:00 dormitorio volumio[1783]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:37:00 dormitorio volumio[1783]: at doSend (dgram.js:692:16) Dec 16 05:37:00 dormitorio volumio[1783]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:37:00 dormitorio volumio[1783]: at afterDns (dgram.js:638:5) Dec 16 05:37:00 dormitorio volumio[1783]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:37:00 dormitorio volumio[1783]: errno: -101, Dec 16 05:37:00 dormitorio volumio[1783]: code: 'ENETUNREACH', Dec 16 05:37:00 dormitorio volumio[1783]: syscall: 'send', Dec 16 05:37:00 dormitorio volumio[1783]: address: '255.255.255.255', Dec 16 05:37:00 dormitorio volumio[1783]: port: 3483 Dec 16 05:37:00 dormitorio volumio[1783]: } Dec 16 05:37:00 dormitorio volumio[1783]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:01 dormitorio sudo[1967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:36 Dec 16 05:37:01 dormitorio sudo[1967]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:01 dormitorio sudo[1967]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:01 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 05:37:01 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 05:37:01 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:01 dormitorio sudo[1919]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:01 dormitorio sudo[1929]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:01 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:37:01 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:01 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:01 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:37:01 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Dec 16 05:37:01 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:01 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:37:01 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:37:01 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:02 dormitorio mpd[1947]: Dec 16 05:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:37:02 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:37:03 dormitorio systemd[1]: nmbd.service: Start operation timed out. Terminating. Dec 16 05:37:03 dormitorio systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Dec 16 05:37:03 dormitorio systemd[1]: nmbd.service: Failed with result 'timeout'. Dec 16 05:37:03 dormitorio systemd[1]: Failed to start Samba NMB Daemon. Dec 16 05:37:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:03 dormitorio systemd[1]: Starting Samba Winbind Daemon... Dec 16 05:37:03 dormitorio volumio[1981]: info: ------------------------------------------- Dec 16 05:37:03 dormitorio volumio[1981]: info: ----- Volumio3 ---- Dec 16 05:37:03 dormitorio volumio[1981]: info: ------------------------------------------- Dec 16 05:37:03 dormitorio volumio[1981]: info: ----- System startup ---- Dec 16 05:37:03 dormitorio volumio[1981]: info: ------------------------------------------- Dec 16 05:37:03 dormitorio winbindd[2003]: [2024/12/16 05:37:03.715840, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 16 05:37:03 dormitorio winbindd[2003]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 16 05:37:03 dormitorio winbindd[2003]: [2024/12/16 05:37:03.751933, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 05:37:03 dormitorio systemd[1]: Started Samba Winbind Daemon. Dec 16 05:37:03 dormitorio winbindd[2003]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 16 05:37:03 dormitorio systemd[1]: Starting Samba SMB Daemon... Dec 16 05:37:04 dormitorio winbindd[2003]: [2024/12/16 05:37:04.241158, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 16 05:37:04 dormitorio winbindd[2003]: res_names->count = 3, expected 4 Dec 16 05:37:04 dormitorio volumio[1981]: info: MYVOLUMIO Environment detected Dec 16 05:37:04 dormitorio smbd[2012]: [2024/12/16 05:37:04.301605, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 16 05:37:04 dormitorio systemd[1]: Started Samba SMB Daemon. Dec 16 05:37:04 dormitorio smbd[2012]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 16 05:37:04 dormitorio volumio[1981]: info: Plugin folders cleanup Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category audio_interface Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category miscellanea Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category music_service Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category plugins.json Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category system_controller Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category user_interface Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning into folder /data/plugins/ Dec 16 05:37:04 dormitorio volumio[1981]: info: Scanning category music_service Dec 16 05:37:04 dormitorio volumio[1981]: info: Plugin folders cleanup completed Dec 16 05:37:04 dormitorio volumio[1981]: info: ------------------------------------------- Dec 16 05:37:04 dormitorio volumio[1981]: info: ----- Core plugins startup ---- Dec 16 05:37:04 dormitorio volumio[1981]: info: ------------------------------------------- Dec 16 05:37:04 dormitorio volumio[1981]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:37:04 dormitorio volumio[1981]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:37:04 dormitorio volumio[1981]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:37:04 dormitorio volumio[1981]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:37:04 dormitorio volumio[1981]: info: Loading plugins from folder /data/plugins/ Dec 16 05:37:04 dormitorio volumio[1981]: info: Loading plugin "system"... Dec 16 05:37:04 dormitorio volumio[1981]: info: Loading plugin "appearance"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "network"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Refreshing Cached IP Addresses Dec 16 05:37:05 dormitorio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:37:05 dormitorio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:05 dormitorio sudo[2026]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:05 dormitorio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:37:05 dormitorio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:05 dormitorio sudo[2028]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "services"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "alsa_controller"... Dec 16 05:37:05 dormitorio sudo[2037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:37:05 dormitorio sudo[2037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:05 dormitorio sudo[2037]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:05 dormitorio volumio[1981]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:37:05 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "wizard"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "networkfs"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Starting Udev Watcher for removable devices Dec 16 05:37:05 dormitorio volumio[1981]: info: Ignoring mount for partition: boot Dec 16 05:37:05 dormitorio volumio[1981]: info: Ignoring mount for partition: volumio Dec 16 05:37:05 dormitorio volumio[1981]: info: Ignoring mount for partition: volumio_data Dec 16 05:37:05 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "upnp"... Dec 16 05:37:05 dormitorio volumio[1981]: info: [1734338225525] Starting Upmpd Daemon Dec 16 05:37:05 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "my_music"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "mpd"... Dec 16 05:37:05 dormitorio volumio[1981]: info: Loading plugin "upnp_browser"... Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "alarm-clock"... Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "airplay_emulation"... Dec 16 05:37:06 dormitorio volumio[1981]: info: Starting Shairport Sync Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "last_100"... Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "webradio"... Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "i2s_dacs"... Dec 16 05:37:06 dormitorio volumio[1981]: info: I2S DAC not set, start Auto-detection Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "volumiodiscovery"... Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** For more information see Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:06 dormitorio volumio[1981]: *** WARNING *** For more information see Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** For more information see Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:06 dormitorio node[1981]: *** WARNING *** For more information see Dec 16 05:37:06 dormitorio volumio[1981]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:37:06 dormitorio volumio[1981]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:37:06 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:06 dormitorio volumio[1981]: info: Loading plugin "spop"... Dec 16 05:37:06 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:06] [connect] Successful connection Dec 16 05:37:07 dormitorio volumio[1981]: info: Loading plugin "squeezelite_mc"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "outputs"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "albumart"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Plugin example_plugin is not enabled Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "inputs"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "updater_comm"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Plugin mpdemulation is not enabled Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "rest_api"... Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading plugin "websocket"... Dec 16 05:37:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:08 dormitorio volumio[1981]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:37:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:08 dormitorio volumio[1981]: info: Loading i18n strings for locale es Dec 16 05:37:08 dormitorio volumio[1981]: Updating browse sources language Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:08 dormitorio volumio[1981]: Forking 3 albumart workers Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::initPlayerControls Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:08 dormitorio volumio[1981]: Express server listening on port 3000 Dec 16 05:37:08 dormitorio volumio[1981]: [Metrics] WebUI: 5s 680.42ms Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreStateMachine::resetVolumioState Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreStateMachine::getcurrentVolume Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:08 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:08 dormitorio volumio[1981]: info: Cannot read play queue from file Dec 16 05:37:08 dormitorio volumio[1981]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:37:08 dormitorio volumio[1981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:08 dormitorio volumio[1981]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:37:08 dormitorio volumio[1981]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:37:08 dormitorio volumio[1981]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:37:08 dormitorio sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:37:08 dormitorio sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:09 dormitorio volumio[1981]: Starting albumart workers Dec 16 05:37:09 dormitorio volumio[1981]: Starting albumart workers Dec 16 05:37:09 dormitorio volumio[1981]: Starting albumart workers Dec 16 05:37:09 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:37:12 dormitorio sudo[2095]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:12 dormitorio volumio[1981]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::pushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::updateTrackBlock Dec 16 05:37:12 dormitorio volumio[1981]: info: CorePlayQueue::getTrackBlock Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:12 dormitorio volumio[1981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::pushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::setRandom null Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::pushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:12 dormitorio volumio[1981]: info: Setting Device type: Raspberry PI Dec 16 05:37:12 dormitorio volumio[1981]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreStateMachine::pushState Dec 16 05:37:12 dormitorio volumio[1981]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:12 dormitorio volumio[1981]: info: Completed loading Core Plugins Dec 16 05:37:12 dormitorio volumio[1981]: info: Preparing to generate the ALSA configuration file Dec 16 05:37:12 dormitorio volumio[1981]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:37:12 dormitorio volumio[1981]: info: Output device has changed, restarting MPD Dec 16 05:37:12 dormitorio volumio[1981]: info: Output device has changed, restarting Shairport Sync Dec 16 05:37:12 dormitorio sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:12 dormitorio sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:12 dormitorio sudo[2128]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:12 dormitorio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:12 dormitorio sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:12 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:37:12 dormitorio volumio[1981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:12 dormitorio volumio[1981]: info: ___________ START PLUGINS ___________ Dec 16 05:37:12 dormitorio volumio[1981]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:37:12 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:12 dormitorio volumio[1981]: info: Creating MPD Configuration file Dec 16 05:37:12 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:12 dormitorio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:12 dormitorio volumio[1981]: info: [1734338232709] CoreMusicLibrary::Adding element Media Servers Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:12 dormitorio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:12 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:12 dormitorio sudo[2138]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:12 dormitorio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:12 dormitorio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:12 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:37:12 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:12 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:12 dormitorio volumio[1981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:12 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:12 dormitorio volumio[1981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:12 dormitorio volumio[1981]: info: [1734338232824] CoreMusicLibrary::Adding element Last_100 Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:12 dormitorio volumio[1981]: info: [1734338232828] CoreMusicLibrary::Adding element Webradio Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:12 dormitorio volumio[1981]: info: Initializing BBC Radios Dec 16 05:37:12 dormitorio sudo[2146]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:37:12 dormitorio sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:12 dormitorio sudo[2146]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:12 dormitorio volumio[1981]: info: Creating Spotify config file Dec 16 05:37:12 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:12 dormitorio volumio[1981]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:37:12 dormitorio volumio[1981]: info: Volumio Calling Home Dec 16 05:37:13 dormitorio volumio[1981]: info: [squeezelite_mc] Proxy server started on port 37273 Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:13 dormitorio volumio[1981]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:37:13 dormitorio volumio[1981]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:37:13 dormitorio volumio[1981]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:37:13 dormitorio volumio[1981]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:37:13 dormitorio volumio[1981]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:37:13 dormitorio volumio[1981]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:37:13 dormitorio volumio[1981]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:37:13 dormitorio volumio[1981]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:37:13 dormitorio volumio[1981]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:37:13 dormitorio volumio[1981]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:37:13 dormitorio volumio[1981]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:37:13 dormitorio volumio[1981]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:37:13 dormitorio volumio[1981]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:37:13 dormitorio volumio[1981]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:37:13 dormitorio volumio[1981]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:37:13 dormitorio volumio[1981]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:37:13 dormitorio volumio[1981]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:37:13 dormitorio volumio[1981]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:13 dormitorio volumio[1981]: info: MPD Permissions set Dec 16 05:37:13 dormitorio volumio[1981]: info: MPD Permissions set Dec 16 05:37:13 dormitorio volumio[1981]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreStateMachine::pushState Dec 16 05:37:13 dormitorio volumio[1981]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:13 dormitorio volumio[1981]: info: [squeezelite_mc] Server discovery started Dec 16 05:37:13 dormitorio volumio[1981]: info: [squeezelite_mc] Player finder started Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:13 dormitorio volumio[1981]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:13 dormitorio volumio[1981]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:37:13 dormitorio volumio[1981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:13 dormitorio volumio[1981]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:37:13 dormitorio volumio[1981]: at doSend (dgram.js:692:16) Dec 16 05:37:13 dormitorio volumio[1981]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:37:13 dormitorio volumio[1981]: at afterDns (dgram.js:638:5) Dec 16 05:37:13 dormitorio volumio[1981]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:37:13 dormitorio volumio[1981]: errno: -101, Dec 16 05:37:13 dormitorio volumio[1981]: code: 'ENETUNREACH', Dec 16 05:37:13 dormitorio volumio[1981]: syscall: 'send', Dec 16 05:37:13 dormitorio volumio[1981]: address: '255.255.255.255', Dec 16 05:37:13 dormitorio volumio[1981]: port: 3483 Dec 16 05:37:13 dormitorio volumio[1981]: } Dec 16 05:37:13 dormitorio volumio[1981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:13 dormitorio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:36 Dec 16 05:37:13 dormitorio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:13 dormitorio sudo[2183]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:14 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:14 dormitorio sudo[2130]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:14 dormitorio sudo[2140]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:14 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:37:14 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:14 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:14 dormitorio mpd[2161]: Dec 16 05:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:37:14 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:37:14 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 16 05:37:14 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:37:14 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:14 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:37:14 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:37:14 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:16 dormitorio volumio[2199]: info: ------------------------------------------- Dec 16 05:37:16 dormitorio volumio[2199]: info: ----- Volumio3 ---- Dec 16 05:37:16 dormitorio volumio[2199]: info: ------------------------------------------- Dec 16 05:37:16 dormitorio volumio[2199]: info: ----- System startup ---- Dec 16 05:37:16 dormitorio volumio[2199]: info: ------------------------------------------- Dec 16 05:37:16 dormitorio volumio[2199]: info: MYVOLUMIO Environment detected Dec 16 05:37:17 dormitorio volumio[2199]: info: Plugin folders cleanup Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category audio_interface Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category miscellanea Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category music_service Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category plugins.json Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category system_controller Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category user_interface Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning into folder /data/plugins/ Dec 16 05:37:17 dormitorio volumio[2199]: info: Scanning category music_service Dec 16 05:37:17 dormitorio volumio[2199]: info: Plugin folders cleanup completed Dec 16 05:37:17 dormitorio volumio[2199]: info: ------------------------------------------- Dec 16 05:37:17 dormitorio volumio[2199]: info: ----- Core plugins startup ---- Dec 16 05:37:17 dormitorio volumio[2199]: info: ------------------------------------------- Dec 16 05:37:17 dormitorio volumio[2199]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:37:17 dormitorio volumio[2199]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:37:17 dormitorio volumio[2199]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:37:17 dormitorio volumio[2199]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:37:17 dormitorio volumio[2199]: info: Loading plugins from folder /data/plugins/ Dec 16 05:37:17 dormitorio volumio[2199]: info: Loading plugin "system"... Dec 16 05:37:17 dormitorio volumio[2199]: info: Loading plugin "appearance"... Dec 16 05:37:18 dormitorio kernel: hwmon hwmon1: Undervoltage detected! Dec 16 05:37:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:18 dormitorio volumio[2199]: info: Loading plugin "network"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Refreshing Cached IP Addresses Dec 16 05:37:19 dormitorio sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:37:19 dormitorio sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:19 dormitorio sudo[2234]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:19 dormitorio sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:37:19 dormitorio sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:19 dormitorio sudo[2236]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "services"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "alsa_controller"... Dec 16 05:37:19 dormitorio sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:37:19 dormitorio sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:19 dormitorio volumio[2199]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:37:19 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "wizard"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "networkfs"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Starting Udev Watcher for removable devices Dec 16 05:37:19 dormitorio volumio[2199]: info: Ignoring mount for partition: boot Dec 16 05:37:19 dormitorio volumio[2199]: info: Ignoring mount for partition: volumio Dec 16 05:37:19 dormitorio volumio[2199]: info: Ignoring mount for partition: volumio_data Dec 16 05:37:19 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "upnp"... Dec 16 05:37:19 dormitorio volumio[2199]: info: [1734338239416] Starting Upmpd Daemon Dec 16 05:37:19 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "my_music"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "mpd"... Dec 16 05:37:19 dormitorio volumio[2199]: info: Loading plugin "upnp_browser"... Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "alarm-clock"... Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "airplay_emulation"... Dec 16 05:37:20 dormitorio volumio[2199]: info: Starting Shairport Sync Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "last_100"... Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "webradio"... Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "i2s_dacs"... Dec 16 05:37:20 dormitorio volumio[2199]: info: I2S DAC not set, start Auto-detection Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "volumiodiscovery"... Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** For more information see Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:20 dormitorio volumio[2199]: *** WARNING *** For more information see Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** For more information see Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:20 dormitorio node[2199]: *** WARNING *** For more information see Dec 16 05:37:20 dormitorio volumio[2199]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:37:20 dormitorio volumio[2199]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:37:20 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:20 dormitorio volumio[2199]: info: Loading plugin "spop"... Dec 16 05:37:21 dormitorio volumio[2199]: info: Loading plugin "squeezelite_mc"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "outputs"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "albumart"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Plugin example_plugin is not enabled Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "inputs"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "updater_comm"... Dec 16 05:37:22 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:22] [connect] Successful connection Dec 16 05:37:22 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:37:22 dormitorio kernel: hwmon hwmon1: Voltage normalised Dec 16 05:37:22 dormitorio volumio[2199]: info: Plugin mpdemulation is not enabled Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "rest_api"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading plugin "websocket"... Dec 16 05:37:22 dormitorio volumio[2199]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:37:22 dormitorio volumio[2199]: info: Loading i18n strings for locale es Dec 16 05:37:22 dormitorio volumio[2199]: Updating browse sources language Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:22 dormitorio volumio[2199]: Forking 3 albumart workers Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::initPlayerControls Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: Express server listening on port 3000 Dec 16 05:37:22 dormitorio volumio[2199]: [Metrics] WebUI: 7s 166.05ms Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreStateMachine::resetVolumioState Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreStateMachine::getcurrentVolume Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:22 dormitorio volumio[2199]: info: Cannot read play queue from file Dec 16 05:37:22 dormitorio volumio[2199]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:37:22 dormitorio volumio[2199]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:22 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734338239 101 Dec 16 05:37:22 dormitorio volumio[2199]: 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: 1 Dec 16 05:37:22 dormitorio volumio[2199]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreStateMachine::pushState Dec 16 05:37:22 dormitorio volumio[2199]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreStateMachine::updateTrackBlock Dec 16 05:37:22 dormitorio volumio[2199]: info: CorePlayQueue::getTrackBlock Dec 16 05:37:22 dormitorio volumio[2199]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:22 dormitorio volumio[2199]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:23 dormitorio volumio[2199]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreStateMachine::pushState Dec 16 05:37:23 dormitorio volumio[2199]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreStateMachine::pushState Dec 16 05:37:23 dormitorio volumio[2199]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreStateMachine::setRandom null Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreStateMachine::pushState Dec 16 05:37:23 dormitorio volumio[2199]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:23 dormitorio volumio[2199]: info: Setting Device type: Raspberry PI Dec 16 05:37:23 dormitorio volumio[2199]: info: Completed loading Core Plugins Dec 16 05:37:23 dormitorio volumio[2199]: info: Preparing to generate the ALSA configuration file Dec 16 05:37:23 dormitorio volumio[2199]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:37:23 dormitorio volumio[2199]: info: Output device has changed, restarting MPD Dec 16 05:37:23 dormitorio volumio[2199]: info: Output device has changed, restarting Shairport Sync Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:23 dormitorio sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:23 dormitorio sudo[2302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:23 dormitorio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:23 dormitorio sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:23 dormitorio sudo[2302]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:23 dormitorio volumio[2199]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:23 dormitorio volumio[2199]: info: ___________ START PLUGINS ___________ Dec 16 05:37:23 dormitorio volumio[2199]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:37:23 dormitorio volumio[2199]: info: Creating MPD Configuration file Dec 16 05:37:23 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:37:23 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:23 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:23 dormitorio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:23 dormitorio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:23 dormitorio volumio[2199]: info: [1734338243359] CoreMusicLibrary::Adding element Media Servers Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:23 dormitorio sudo[2311]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:23 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:23 dormitorio sudo[2315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:23 dormitorio sudo[2315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:23 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:37:23 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:23 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:23 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:23 dormitorio volumio[2199]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:23 dormitorio volumio[2199]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:23 dormitorio volumio[2199]: info: [1734338243558] CoreMusicLibrary::Adding element Last_100 Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:23 dormitorio volumio[2199]: info: [1734338243563] CoreMusicLibrary::Adding element Webradio Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:23 dormitorio sudo[2317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:37:23 dormitorio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:23 dormitorio volumio[2199]: info: Initializing BBC Radios Dec 16 05:37:23 dormitorio sudo[2317]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:23 dormitorio volumio[2199]: Starting albumart workers Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: info: Creating Spotify config file Dec 16 05:37:23 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:23 dormitorio volumio[2199]: Starting albumart workers Dec 16 05:37:23 dormitorio volumio[2199]: Starting albumart workers Dec 16 05:37:23 dormitorio volumio[2199]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:37:23 dormitorio volumio[2199]: info: Volumio Calling Home Dec 16 05:37:23 dormitorio volumio[2199]: info: [squeezelite_mc] Proxy server started on port 44251 Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:24 dormitorio volumio[2199]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:37:24 dormitorio volumio[2199]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:37:24 dormitorio volumio[2199]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:37:24 dormitorio volumio[2199]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:37:24 dormitorio volumio[2199]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:37:24 dormitorio volumio[2199]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:37:24 dormitorio volumio[2199]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:37:24 dormitorio volumio[2199]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:37:24 dormitorio volumio[2199]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:37:24 dormitorio volumio[2199]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:37:24 dormitorio volumio[2199]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:37:24 dormitorio volumio[2199]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:37:24 dormitorio volumio[2199]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:37:24 dormitorio volumio[2199]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:37:24 dormitorio volumio[2199]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:37:24 dormitorio volumio[2199]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:37:24 dormitorio volumio[2199]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:37:24 dormitorio volumio[2199]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:24 dormitorio volumio[2199]: info: MPD Permissions set Dec 16 05:37:24 dormitorio volumio[2199]: info: MPD Permissions set Dec 16 05:37:24 dormitorio volumio[2199]: info: Spotify config file written Dec 16 05:37:24 dormitorio volumio[2199]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 16 05:37:24 dormitorio volumio[2199]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreStateMachine::pushState Dec 16 05:37:24 dormitorio volumio[2199]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:24 dormitorio sudo[2347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 16 05:37:24 dormitorio sudo[2347]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:24 dormitorio volumio[2199]: info: [squeezelite_mc] Server discovery started Dec 16 05:37:24 dormitorio volumio[2199]: info: [squeezelite_mc] Player finder started Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:24 dormitorio volumio[2199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:24 dormitorio volumio[2199]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:37:24 dormitorio volumio[2199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:24 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:24 dormitorio volumio[2199]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:37:24 dormitorio volumio[2199]: at doSend (dgram.js:692:16) Dec 16 05:37:24 dormitorio volumio[2199]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:37:24 dormitorio volumio[2199]: at afterDns (dgram.js:638:5) Dec 16 05:37:24 dormitorio volumio[2199]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:37:24 dormitorio volumio[2199]: errno: -101, Dec 16 05:37:24 dormitorio volumio[2199]: code: 'ENETUNREACH', Dec 16 05:37:24 dormitorio volumio[2199]: syscall: 'send', Dec 16 05:37:24 dormitorio volumio[2199]: address: '255.255.255.255', Dec 16 05:37:24 dormitorio volumio[2199]: port: 3483 Dec 16 05:37:24 dormitorio volumio[2199]: } Dec 16 05:37:24 dormitorio volumio[2199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:24 dormitorio go-librespot[2349]: Librespot-go daemon starting... Dec 16 05:37:24 dormitorio sudo[2347]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:24 dormitorio sudo[2245]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:25 dormitorio go-librespot[2349]: time="2024-12-16T05:37:25-03:00" level=info msg="generated new device id: 8efd723b03debed1cffd777908be8e71d31b0e4a" Dec 16 05:37:25 dormitorio go-librespot[2349]: time="2024-12-16T05:37:25-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:25 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:25 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:25 dormitorio sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:36 Dec 16 05:37:25 dormitorio sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:25 dormitorio sudo[2391]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:25 dormitorio mpd[2331]: Dec 16 05:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:37:25 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:37:25 dormitorio sudo[2315]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:25 dormitorio sudo[2304]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:25 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 05:37:25 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 05:37:25 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:25 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:37:25 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:25 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:25 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:37:25 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 16 05:37:25 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:37:25 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:37:25 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:25 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:27 dormitorio volumio[2406]: info: ------------------------------------------- Dec 16 05:37:27 dormitorio volumio[2406]: info: ----- Volumio3 ---- Dec 16 05:37:27 dormitorio volumio[2406]: info: ------------------------------------------- Dec 16 05:37:27 dormitorio volumio[2406]: info: ----- System startup ---- Dec 16 05:37:27 dormitorio volumio[2406]: info: ------------------------------------------- Dec 16 05:37:28 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:28 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 16 05:37:28 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:28 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:28 dormitorio go-librespot[2427]: Librespot-go daemon starting... Dec 16 05:37:28 dormitorio go-librespot[2427]: time="2024-12-16T05:37:28-03:00" level=info msg="generated new device id: 8e2ab7d7452af0d8e165d1ff1f8c11450925531d" Dec 16 05:37:28 dormitorio go-librespot[2427]: time="2024-12-16T05:37:28-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:28 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:28 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:28 dormitorio volumio[2406]: info: MYVOLUMIO Environment detected Dec 16 05:37:28 dormitorio volumio[2406]: info: Plugin folders cleanup Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category audio_interface Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category miscellanea Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category music_service Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category plugins.json Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category system_controller Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category user_interface Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning into folder /data/plugins/ Dec 16 05:37:28 dormitorio volumio[2406]: info: Scanning category music_service Dec 16 05:37:28 dormitorio volumio[2406]: info: Plugin folders cleanup completed Dec 16 05:37:28 dormitorio volumio[2406]: info: ------------------------------------------- Dec 16 05:37:28 dormitorio volumio[2406]: info: ----- Core plugins startup ---- Dec 16 05:37:28 dormitorio volumio[2406]: info: ------------------------------------------- Dec 16 05:37:28 dormitorio volumio[2406]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:37:28 dormitorio volumio[2406]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:37:28 dormitorio volumio[2406]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:37:28 dormitorio volumio[2406]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:37:28 dormitorio volumio[2406]: info: Loading plugins from folder /data/plugins/ Dec 16 05:37:28 dormitorio volumio[2406]: info: Loading plugin "system"... Dec 16 05:37:28 dormitorio volumio[2406]: info: Loading plugin "appearance"... Dec 16 05:37:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "network"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Refreshing Cached IP Addresses Dec 16 05:37:29 dormitorio sudo[2449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:37:29 dormitorio sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:29 dormitorio sudo[2451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:37:29 dormitorio sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:29 dormitorio sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:29 dormitorio sudo[2451]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "services"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "alsa_controller"... Dec 16 05:37:29 dormitorio sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:37:29 dormitorio sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:29 dormitorio sudo[2460]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:29 dormitorio volumio[2406]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:37:29 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "wizard"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "networkfs"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Starting Udev Watcher for removable devices Dec 16 05:37:29 dormitorio volumio[2406]: info: Ignoring mount for partition: boot Dec 16 05:37:29 dormitorio volumio[2406]: info: Ignoring mount for partition: volumio Dec 16 05:37:29 dormitorio volumio[2406]: info: Ignoring mount for partition: volumio_data Dec 16 05:37:29 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "upnp"... Dec 16 05:37:29 dormitorio volumio[2406]: info: [1734338249539] Starting Upmpd Daemon Dec 16 05:37:29 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "my_music"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "mpd"... Dec 16 05:37:29 dormitorio volumio[2406]: info: Loading plugin "upnp_browser"... Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "alarm-clock"... Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "airplay_emulation"... Dec 16 05:37:30 dormitorio volumio[2406]: info: Starting Shairport Sync Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "last_100"... Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "webradio"... Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "i2s_dacs"... Dec 16 05:37:30 dormitorio volumio[2406]: info: I2S DAC not set, start Auto-detection Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "volumiodiscovery"... Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** For more information see Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:30 dormitorio volumio[2406]: *** WARNING *** For more information see Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** For more information see Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:30 dormitorio node[2406]: *** WARNING *** For more information see Dec 16 05:37:30 dormitorio volumio[2406]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:37:30 dormitorio volumio[2406]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:37:30 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:30 dormitorio volumio[2406]: info: Loading plugin "spop"... Dec 16 05:37:30 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:30] [connect] Successful connection Dec 16 05:37:31 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:31 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 16 05:37:31 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:31 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:31 dormitorio go-librespot[2476]: Librespot-go daemon starting... Dec 16 05:37:31 dormitorio go-librespot[2476]: time="2024-12-16T05:37:31-03:00" level=info msg="generated new device id: e6db44c68834a36613e5687db5d72b746a52e361" Dec 16 05:37:31 dormitorio go-librespot[2476]: time="2024-12-16T05:37:31-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:31 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:31 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:31 dormitorio volumio[2406]: info: Loading plugin "squeezelite_mc"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "outputs"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "albumart"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Plugin example_plugin is not enabled Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "inputs"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "updater_comm"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Plugin mpdemulation is not enabled Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "rest_api"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading plugin "websocket"... Dec 16 05:37:32 dormitorio volumio[2406]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:37:32 dormitorio volumio[2406]: info: Loading i18n strings for locale es Dec 16 05:37:32 dormitorio volumio[2406]: Updating browse sources language Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:32 dormitorio volumio[2406]: Forking 3 albumart workers Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::initPlayerControls Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:32 dormitorio volumio[2406]: Express server listening on port 3000 Dec 16 05:37:32 dormitorio volumio[2406]: [Metrics] WebUI: 5s 706.34ms Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreStateMachine::resetVolumioState Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreStateMachine::getcurrentVolume Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:32 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:32 dormitorio volumio[2406]: info: Cannot read play queue from file Dec 16 05:37:32 dormitorio volumio[2406]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:37:32 dormitorio volumio[2406]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:32 dormitorio volumio[2406]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:37:32 dormitorio volumio[2406]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:37:32 dormitorio volumio[2406]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:37:32 dormitorio sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:37:32 dormitorio sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:33 dormitorio volumio[2406]: Starting albumart workers Dec 16 05:37:33 dormitorio volumio[2406]: Starting albumart workers Dec 16 05:37:33 dormitorio volumio[2406]: Starting albumart workers Dec 16 05:37:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:34 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:37:34 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:34 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 16 05:37:34 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:34 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:34 dormitorio go-librespot[2586]: Librespot-go daemon starting... Dec 16 05:37:34 dormitorio go-librespot[2586]: time="2024-12-16T05:37:34-03:00" level=info msg="generated new device id: 07c2f76993abed37d6642ffb47cb631293ceec0f" Dec 16 05:37:34 dormitorio go-librespot[2586]: time="2024-12-16T05:37:34-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:34 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:34 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:37 dormitorio sudo[2555]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:37 dormitorio volumio[2406]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::pushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::updateTrackBlock Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrackBlock Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:37 dormitorio volumio[2406]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::pushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::setRandom null Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::pushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:37 dormitorio volumio[2406]: info: Setting Device type: Raspberry PI Dec 16 05:37:37 dormitorio volumio[2406]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::pushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:37 dormitorio volumio[2406]: info: Completed loading Core Plugins Dec 16 05:37:37 dormitorio volumio[2406]: info: Preparing to generate the ALSA configuration file Dec 16 05:37:37 dormitorio volumio[2406]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:37:37 dormitorio volumio[2406]: info: Output device has changed, restarting MPD Dec 16 05:37:37 dormitorio sudo[2600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:37 dormitorio volumio[2406]: info: Output device has changed, restarting Shairport Sync Dec 16 05:37:37 dormitorio sudo[2600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:37 dormitorio sudo[2600]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:37 dormitorio sudo[2602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:37 dormitorio sudo[2602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:37 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:37:37 dormitorio volumio[2406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:37 dormitorio volumio[2406]: info: ___________ START PLUGINS ___________ Dec 16 05:37:37 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:37 dormitorio volumio[2406]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:37:37 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:37 dormitorio volumio[2406]: info: Creating MPD Configuration file Dec 16 05:37:37 dormitorio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:37 dormitorio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:37 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:37 dormitorio volumio[2406]: info: [1734338257448] CoreMusicLibrary::Adding element Media Servers Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:37 dormitorio sudo[2610]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:37 dormitorio sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:37 dormitorio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:37 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:37:37 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:37 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:37 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:37 dormitorio volumio[2406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:37 dormitorio volumio[2406]: info: [1734338257560] CoreMusicLibrary::Adding element Last_100 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:37 dormitorio volumio[2406]: info: [1734338257564] CoreMusicLibrary::Adding element Webradio Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:37 dormitorio volumio[2406]: info: Initializing BBC Radios Dec 16 05:37:37 dormitorio sudo[2618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:37:37 dormitorio sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:37 dormitorio sudo[2618]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: Creating Spotify config file Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:37:37 dormitorio volumio[2406]: info: Volumio Calling Home Dec 16 05:37:37 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:37 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 16 05:37:37 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:37 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:37 dormitorio go-librespot[2636]: Librespot-go daemon starting... Dec 16 05:37:37 dormitorio go-librespot[2636]: time="2024-12-16T05:37:37-03:00" level=info msg="generated new device id: 95407f3bc08f6fa745ebe526727d252857356585" Dec 16 05:37:37 dormitorio go-librespot[2636]: time="2024-12-16T05:37:37-03:00" level=fatal msg="unknown credentials: " Dec 16 05:37:37 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:37 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:37 dormitorio volumio[2406]: info: [squeezelite_mc] Proxy server started on port 43599 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:37 dormitorio volumio[2406]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:37:37 dormitorio volumio[2406]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:37:37 dormitorio volumio[2406]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:37:37 dormitorio volumio[2406]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:37:37 dormitorio volumio[2406]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:37:37 dormitorio volumio[2406]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:37:37 dormitorio volumio[2406]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:37:37 dormitorio volumio[2406]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:37:37 dormitorio volumio[2406]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:37:37 dormitorio volumio[2406]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:37:37 dormitorio volumio[2406]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:37:37 dormitorio volumio[2406]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:37:37 dormitorio volumio[2406]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:37:37 dormitorio volumio[2406]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:37:37 dormitorio volumio[2406]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:37:37 dormitorio volumio[2406]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:37:37 dormitorio volumio[2406]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:37:37 dormitorio volumio[2406]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:37 dormitorio volumio[2406]: info: MPD Permissions set Dec 16 05:37:37 dormitorio volumio[2406]: info: MPD Permissions set Dec 16 05:37:37 dormitorio volumio[2406]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreStateMachine::pushState Dec 16 05:37:37 dormitorio volumio[2406]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:37 dormitorio volumio[2406]: info: [squeezelite_mc] Server discovery started Dec 16 05:37:37 dormitorio volumio[2406]: info: [squeezelite_mc] Player finder started Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:37 dormitorio volumio[2406]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:37:38 dormitorio volumio[2406]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:38 dormitorio volumio[2406]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:37:38 dormitorio volumio[2406]: at doSend (dgram.js:692:16) Dec 16 05:37:38 dormitorio volumio[2406]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:37:38 dormitorio volumio[2406]: at afterDns (dgram.js:638:5) Dec 16 05:37:38 dormitorio volumio[2406]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:37:38 dormitorio volumio[2406]: errno: -101, Dec 16 05:37:38 dormitorio volumio[2406]: code: 'ENETUNREACH', Dec 16 05:37:38 dormitorio volumio[2406]: syscall: 'send', Dec 16 05:37:38 dormitorio volumio[2406]: address: '255.255.255.255', Dec 16 05:37:38 dormitorio volumio[2406]: port: 3483 Dec 16 05:37:38 dormitorio volumio[2406]: } Dec 16 05:37:38 dormitorio volumio[2406]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:38 dormitorio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:36 Dec 16 05:37:38 dormitorio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:38 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:38 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:38 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:38 dormitorio sudo[2659]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:38 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:38 dormitorio sudo[2602]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:38 dormitorio sudo[2612]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:38 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:37:38 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:38 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:39 dormitorio mpd[2630]: Dec 16 05:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:37:39 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:37:39 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Dec 16 05:37:39 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:37:39 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:37:39 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:37:39 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:39 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:40 dormitorio volumio[2679]: info: ------------------------------------------- Dec 16 05:37:40 dormitorio volumio[2679]: info: ----- Volumio3 ---- Dec 16 05:37:40 dormitorio volumio[2679]: info: ------------------------------------------- Dec 16 05:37:40 dormitorio volumio[2679]: info: ----- System startup ---- Dec 16 05:37:40 dormitorio volumio[2679]: info: ------------------------------------------- Dec 16 05:37:40 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:40 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 16 05:37:40 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:40 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:40 dormitorio go-librespot[2701]: Librespot-go daemon starting... Dec 16 05:37:40 dormitorio go-librespot[2701]: time="2024-12-16T05:37:40-03:00" level=info msg="generated new device id: b98ce48b6f1760bd89d37e65f8a8733f81882d25" Dec 16 05:37:40 dormitorio go-librespot[2701]: time="2024-12-16T05:37:40-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:40 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:40 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:41 dormitorio volumio[2679]: info: MYVOLUMIO Environment detected Dec 16 05:37:41 dormitorio volumio[2679]: info: Plugin folders cleanup Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category audio_interface Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category miscellanea Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category music_service Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category plugins.json Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category system_controller Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category user_interface Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning into folder /data/plugins/ Dec 16 05:37:41 dormitorio volumio[2679]: info: Scanning category music_service Dec 16 05:37:41 dormitorio volumio[2679]: info: Plugin folders cleanup completed Dec 16 05:37:41 dormitorio volumio[2679]: info: ------------------------------------------- Dec 16 05:37:41 dormitorio volumio[2679]: info: ----- Core plugins startup ---- Dec 16 05:37:41 dormitorio volumio[2679]: info: ------------------------------------------- Dec 16 05:37:41 dormitorio volumio[2679]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:37:41 dormitorio volumio[2679]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:37:41 dormitorio volumio[2679]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:37:41 dormitorio volumio[2679]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:37:41 dormitorio volumio[2679]: info: Loading plugins from folder /data/plugins/ Dec 16 05:37:41 dormitorio volumio[2679]: info: Loading plugin "system"... Dec 16 05:37:41 dormitorio volumio[2679]: info: Loading plugin "appearance"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "network"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Refreshing Cached IP Addresses Dec 16 05:37:42 dormitorio sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:37:42 dormitorio sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:42 dormitorio sudo[2715]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:42 dormitorio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:37:42 dormitorio sudo[2717]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:42 dormitorio sudo[2717]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "services"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "alsa_controller"... Dec 16 05:37:42 dormitorio sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:37:42 dormitorio sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:42 dormitorio sudo[2726]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:42 dormitorio volumio[2679]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:37:42 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "wizard"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "networkfs"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Starting Udev Watcher for removable devices Dec 16 05:37:42 dormitorio volumio[2679]: info: Ignoring mount for partition: boot Dec 16 05:37:42 dormitorio volumio[2679]: info: Ignoring mount for partition: volumio Dec 16 05:37:42 dormitorio volumio[2679]: info: Ignoring mount for partition: volumio_data Dec 16 05:37:42 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "upnp"... Dec 16 05:37:42 dormitorio volumio[2679]: info: [1734338262699] Starting Upmpd Daemon Dec 16 05:37:42 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "my_music"... Dec 16 05:37:42 dormitorio volumio[2679]: info: Loading plugin "mpd"... Dec 16 05:37:43 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:43] [connect] Successful connection Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "upnp_browser"... Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "alarm-clock"... Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "airplay_emulation"... Dec 16 05:37:43 dormitorio volumio[2679]: info: Starting Shairport Sync Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "last_100"... Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "webradio"... Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "i2s_dacs"... Dec 16 05:37:43 dormitorio volumio[2679]: info: I2S DAC not set, start Auto-detection Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "volumiodiscovery"... Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** For more information see Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:43 dormitorio volumio[2679]: *** WARNING *** For more information see Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** For more information see Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:43 dormitorio node[2679]: *** WARNING *** For more information see Dec 16 05:37:43 dormitorio volumio[2679]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:37:43 dormitorio volumio[2679]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:37:43 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:43 dormitorio volumio[2679]: info: Loading plugin "spop"... Dec 16 05:37:43 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:43 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:43 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:44 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:44 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 16 05:37:44 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:44 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:44 dormitorio go-librespot[2747]: Librespot-go daemon starting... Dec 16 05:37:44 dormitorio go-librespot[2747]: time="2024-12-16T05:37:44-03:00" level=info msg="generated new device id: f2eab66546774520c0603560fabc7a64cd7f4589" Dec 16 05:37:44 dormitorio go-librespot[2747]: time="2024-12-16T05:37:44-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:44 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:44 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:44 dormitorio volumio[2679]: info: Loading plugin "squeezelite_mc"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "outputs"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "albumart"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Plugin example_plugin is not enabled Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "inputs"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "updater_comm"... Dec 16 05:37:45 dormitorio ntpd[740]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 16 05:37:45 dormitorio volumio[2679]: info: Plugin mpdemulation is not enabled Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "rest_api"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading plugin "websocket"... Dec 16 05:37:45 dormitorio volumio[2679]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:37:45 dormitorio volumio[2679]: info: Loading i18n strings for locale es Dec 16 05:37:45 dormitorio volumio[2679]: Updating browse sources language Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:45 dormitorio volumio[2679]: Forking 3 albumart workers Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::initPlayerControls Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:45 dormitorio volumio[2679]: Express server listening on port 3000 Dec 16 05:37:45 dormitorio volumio[2679]: [Metrics] WebUI: 5s 629.00ms Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreStateMachine::resetVolumioState Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreStateMachine::getcurrentVolume Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:45 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:46 dormitorio volumio[2679]: info: Cannot read play queue from file Dec 16 05:37:46 dormitorio volumio[2679]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:37:46 dormitorio volumio[2679]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:46 dormitorio volumio[2679]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:37:46 dormitorio volumio[2679]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:37:46 dormitorio volumio[2679]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:37:46 dormitorio sudo[2789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:37:46 dormitorio sudo[2789]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:46 dormitorio volumio[2679]: Starting albumart workers Dec 16 05:37:46 dormitorio volumio[2679]: Starting albumart workers Dec 16 05:37:46 dormitorio volumio[2679]: Starting albumart workers Dec 16 05:37:47 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:37:47 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:47 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 16 05:37:47 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:47 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:47 dormitorio go-librespot[2815]: Librespot-go daemon starting... Dec 16 05:37:47 dormitorio go-librespot[2815]: time="2024-12-16T05:37:47-03:00" level=info msg="generated new device id: 0a091b717df98058bf89854af604d47804ae6e6e" Dec 16 05:37:47 dormitorio go-librespot[2815]: time="2024-12-16T05:37:47-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:47 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:47 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:47 dormitorio ntpd[740]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 16 05:37:47 dormitorio ntpd[740]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 16 05:37:48 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:48 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:48 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:49 dormitorio sudo[2789]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:49 dormitorio volumio[2679]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::pushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::updateTrackBlock Dec 16 05:37:49 dormitorio volumio[2679]: info: CorePlayQueue::getTrackBlock Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:49 dormitorio volumio[2679]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::pushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::setRandom null Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::pushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:49 dormitorio volumio[2679]: info: Setting Device type: Raspberry PI Dec 16 05:37:49 dormitorio volumio[2679]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreStateMachine::pushState Dec 16 05:37:49 dormitorio volumio[2679]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:49 dormitorio volumio[2679]: info: Completed loading Core Plugins Dec 16 05:37:49 dormitorio volumio[2679]: info: Preparing to generate the ALSA configuration file Dec 16 05:37:49 dormitorio volumio[2679]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:37:49 dormitorio volumio[2679]: info: Output device has changed, restarting MPD Dec 16 05:37:49 dormitorio sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:49 dormitorio volumio[2679]: info: Output device has changed, restarting Shairport Sync Dec 16 05:37:49 dormitorio sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:49 dormitorio sudo[2837]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:49 dormitorio sudo[2839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:49 dormitorio sudo[2839]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:49 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:37:49 dormitorio volumio[2679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:49 dormitorio volumio[2679]: info: ___________ START PLUGINS ___________ Dec 16 05:37:49 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:49 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:49 dormitorio volumio[2679]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:37:49 dormitorio volumio[2679]: info: Creating MPD Configuration file Dec 16 05:37:49 dormitorio sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:37:49 dormitorio sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:49 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:49 dormitorio volumio[2679]: info: [1734338269888] CoreMusicLibrary::Adding element Media Servers Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:49 dormitorio sudo[2847]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:49 dormitorio sudo[2849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:37:49 dormitorio sudo[2849]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:49 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:37:49 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:37:49 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:37:49 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:37:49 dormitorio volumio[2679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:49 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:50 dormitorio volumio[2679]: info: [1734338270004] CoreMusicLibrary::Adding element Last_100 Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:37:50 dormitorio volumio[2679]: info: [1734338270007] CoreMusicLibrary::Adding element Webradio Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:50 dormitorio volumio[2679]: info: Initializing BBC Radios Dec 16 05:37:50 dormitorio sudo[2855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:37:50 dormitorio sudo[2855]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:50 dormitorio sudo[2855]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: Creating Spotify config file Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:37:50 dormitorio volumio[2679]: info: Volumio Calling Home Dec 16 05:37:50 dormitorio volumio[2679]: info: [squeezelite_mc] Proxy server started on port 42327 Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:50 dormitorio volumio[2679]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:37:50 dormitorio volumio[2679]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:37:50 dormitorio volumio[2679]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:37:50 dormitorio volumio[2679]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:37:50 dormitorio volumio[2679]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:37:50 dormitorio volumio[2679]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:37:50 dormitorio volumio[2679]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:37:50 dormitorio volumio[2679]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:37:50 dormitorio volumio[2679]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:37:50 dormitorio volumio[2679]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:37:50 dormitorio volumio[2679]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:37:50 dormitorio volumio[2679]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:37:50 dormitorio volumio[2679]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:37:50 dormitorio volumio[2679]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:37:50 dormitorio volumio[2679]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:37:50 dormitorio volumio[2679]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:37:50 dormitorio volumio[2679]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:37:50 dormitorio volumio[2679]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:50 dormitorio volumio[2679]: info: MPD Permissions set Dec 16 05:37:50 dormitorio volumio[2679]: info: MPD Permissions set Dec 16 05:37:50 dormitorio volumio[2679]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreStateMachine::pushState Dec 16 05:37:50 dormitorio volumio[2679]: info: CorePlayQueue::getTrack 0 Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::volumioPushState Dec 16 05:37:50 dormitorio volumio[2679]: info: [squeezelite_mc] Server discovery started Dec 16 05:37:50 dormitorio volumio[2679]: info: [squeezelite_mc] Player finder started Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:50 dormitorio volumio[2679]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:37:50 dormitorio volumio[2679]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:50 dormitorio volumio[2679]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:37:50 dormitorio volumio[2679]: at doSend (dgram.js:692:16) Dec 16 05:37:50 dormitorio volumio[2679]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:37:50 dormitorio volumio[2679]: at afterDns (dgram.js:638:5) Dec 16 05:37:50 dormitorio volumio[2679]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:37:50 dormitorio volumio[2679]: errno: -101, Dec 16 05:37:50 dormitorio volumio[2679]: code: 'ENETUNREACH', Dec 16 05:37:50 dormitorio volumio[2679]: syscall: 'send', Dec 16 05:37:50 dormitorio volumio[2679]: address: '255.255.255.255', Dec 16 05:37:50 dormitorio volumio[2679]: port: 3483 Dec 16 05:37:50 dormitorio volumio[2679]: } Dec 16 05:37:50 dormitorio volumio[2679]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:37:50 dormitorio ntpd[740]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 16 05:37:50 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:50 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 16 05:37:50 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:50 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:50 dormitorio go-librespot[2886]: Librespot-go daemon starting... Dec 16 05:37:50 dormitorio go-librespot[2886]: time="2024-12-16T05:37:50-03:00" level=info msg="generated new device id: 26d878001a53895726db142db9ff19ab259f1e96" Dec 16 05:37:50 dormitorio go-librespot[2886]: time="2024-12-16T05:37:50-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:50 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:50 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:51 dormitorio sudo[2894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:36 Dec 16 05:37:51 dormitorio sudo[2894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:51 dormitorio sudo[2894]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:51 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:51 dormitorio sudo[2849]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:51 dormitorio sudo[2839]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:51 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:37:51 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:51 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:51 dormitorio mpd[2866]: Dec 16 05:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:37:51 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:37:51 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Dec 16 05:37:51 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:37:51 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:37:51 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:37:51 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:37:51 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:37:53 dormitorio volumio[2909]: info: ------------------------------------------- Dec 16 05:37:53 dormitorio volumio[2909]: info: ----- Volumio3 ---- Dec 16 05:37:53 dormitorio volumio[2909]: info: ------------------------------------------- Dec 16 05:37:53 dormitorio volumio[2909]: info: ----- System startup ---- Dec 16 05:37:53 dormitorio volumio[2909]: info: ------------------------------------------- Dec 16 05:37:53 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:53 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:53 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:53 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:53 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 16 05:37:53 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:53 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:53 dormitorio go-librespot[2935]: Librespot-go daemon starting... Dec 16 05:37:53 dormitorio go-librespot[2935]: time="2024-12-16T05:37:53-03:00" level=info msg="generated new device id: 30b7ec80362462b82dde35f3a0419d59f7bf52ac" Dec 16 05:37:53 dormitorio volumio[2909]: info: MYVOLUMIO Environment detected Dec 16 05:37:53 dormitorio go-librespot[2935]: time="2024-12-16T05:37:53-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:53 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:53 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:53 dormitorio volumio[2909]: info: Plugin folders cleanup Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category audio_interface Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category miscellanea Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category music_service Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category plugins.json Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category system_controller Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category user_interface Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning into folder /data/plugins/ Dec 16 05:37:53 dormitorio volumio[2909]: info: Scanning category music_service Dec 16 05:37:54 dormitorio volumio[2909]: info: Plugin folders cleanup completed Dec 16 05:37:54 dormitorio volumio[2909]: info: ------------------------------------------- Dec 16 05:37:54 dormitorio volumio[2909]: info: ----- Core plugins startup ---- Dec 16 05:37:54 dormitorio volumio[2909]: info: ------------------------------------------- Dec 16 05:37:54 dormitorio volumio[2909]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:37:54 dormitorio volumio[2909]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:37:54 dormitorio volumio[2909]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:37:54 dormitorio volumio[2909]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:37:54 dormitorio volumio[2909]: info: Loading plugins from folder /data/plugins/ Dec 16 05:37:54 dormitorio volumio[2909]: info: Loading plugin "system"... Dec 16 05:37:54 dormitorio volumio[2909]: info: Loading plugin "appearance"... Dec 16 05:37:54 dormitorio volumio[2909]: info: Loading plugin "network"... Dec 16 05:37:54 dormitorio volumio[2909]: info: Refreshing Cached IP Addresses Dec 16 05:37:54 dormitorio sudo[2948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:37:54 dormitorio sudo[2948]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:54 dormitorio sudo[2948]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:54 dormitorio sudo[2950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:37:54 dormitorio sudo[2950]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:55 dormitorio sudo[2950]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "services"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "alsa_controller"... Dec 16 05:37:55 dormitorio sudo[2963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:37:55 dormitorio sudo[2963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:55 dormitorio sudo[2963]: pam_unix(sudo:session): session closed for user root Dec 16 05:37:55 dormitorio volumio[2909]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:37:55 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "wizard"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "networkfs"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Starting Udev Watcher for removable devices Dec 16 05:37:55 dormitorio volumio[2909]: info: Ignoring mount for partition: boot Dec 16 05:37:55 dormitorio volumio[2909]: info: Ignoring mount for partition: volumio Dec 16 05:37:55 dormitorio volumio[2909]: info: Ignoring mount for partition: volumio_data Dec 16 05:37:55 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "upnp"... Dec 16 05:37:55 dormitorio volumio[2909]: info: [1734338275157] Starting Upmpd Daemon Dec 16 05:37:55 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "my_music"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "mpd"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "upnp_browser"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "alarm-clock"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "airplay_emulation"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Starting Shairport Sync Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "last_100"... Dec 16 05:37:55 dormitorio volumio[2909]: info: Loading plugin "webradio"... Dec 16 05:37:56 dormitorio volumio[2909]: info: Loading plugin "i2s_dacs"... Dec 16 05:37:56 dormitorio volumio[2909]: info: I2S DAC not set, start Auto-detection Dec 16 05:37:56 dormitorio volumio[2909]: info: Loading plugin "volumiodiscovery"... Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** For more information see Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:56 dormitorio volumio[2909]: *** WARNING *** For more information see Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** For more information see Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:37:56 dormitorio node[2909]: *** WARNING *** For more information see Dec 16 05:37:56 dormitorio volumio[2909]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:37:56 dormitorio volumio[2909]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:37:56 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:37:56 dormitorio volumio[2909]: info: Loading plugin "spop"... Dec 16 05:37:57 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:37:57 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 16 05:37:57 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "squeezelite_mc"... Dec 16 05:37:57 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:37:57 dormitorio go-librespot[2975]: Librespot-go daemon starting... Dec 16 05:37:57 dormitorio go-librespot[2975]: time="2024-12-16T05:37:57-03:00" level=info msg="generated new device id: c5a51956370f5d4dcc01cc14d2eae4949f39fa1d" Dec 16 05:37:57 dormitorio go-librespot[2975]: time="2024-12-16T05:37:57-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:37:57 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:37:57 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "outputs"... Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "albumart"... Dec 16 05:37:57 dormitorio volumio[2909]: info: Plugin example_plugin is not enabled Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "inputs"... Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "updater_comm"... Dec 16 05:37:57 dormitorio volumio[2909]: info: Plugin mpdemulation is not enabled Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "rest_api"... Dec 16 05:37:57 dormitorio volumio[2909]: info: Loading plugin "websocket"... Dec 16 05:37:58 dormitorio volumio[2909]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:37:58 dormitorio volumio[2909]: info: Loading i18n strings for locale es Dec 16 05:37:58 dormitorio volumio-remote-updater[607]: [2024-12-16 05:37:58] [connect] Successful connection Dec 16 05:37:58 dormitorio volumio[2909]: Updating browse sources language Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:37:58 dormitorio volumio[2909]: Forking 3 albumart workers Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::initPlayerControls Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:37:58 dormitorio volumio[2909]: Express server listening on port 3000 Dec 16 05:37:58 dormitorio volumio[2909]: [Metrics] WebUI: 5s 519.30ms Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreStateMachine::resetVolumioState Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreStateMachine::getcurrentVolume Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:37:58 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:37:58 dormitorio volumio[2909]: info: Cannot read play queue from file Dec 16 05:37:58 dormitorio volumio[2909]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:37:58 dormitorio volumio[2909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:37:58 dormitorio volumio[2909]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:37:58 dormitorio volumio[2909]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:37:58 dormitorio volumio[2909]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:37:58 dormitorio sudo[3016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:37:58 dormitorio sudo[3016]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:37:58 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:37:58 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:37:58 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:37:59 dormitorio volumio[2909]: Starting albumart workers Dec 16 05:37:59 dormitorio volumio[2909]: Starting albumart workers Dec 16 05:37:59 dormitorio volumio[2909]: Starting albumart workers Dec 16 05:37:59 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:38:00 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:00 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 16 05:38:00 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:00 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:00 dormitorio go-librespot[3050]: Librespot-go daemon starting... Dec 16 05:38:00 dormitorio go-librespot[3050]: time="2024-12-16T05:38:00-03:00" level=info msg="generated new device id: 82fd8a0305db7a729e5716fff823c9a18b902b6d" Dec 16 05:38:00 dormitorio go-librespot[3050]: time="2024-12-16T05:38:00-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:00 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:00 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:01 dormitorio sudo[3016]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:01 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734338278 101 Dec 16 05:38:02 dormitorio volumio[2909]: 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: 1 Dec 16 05:38:02 dormitorio volumio[2909]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::pushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::updateTrackBlock Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrackBlock Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:02 dormitorio volumio[2909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::pushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::setRandom null Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::pushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:02 dormitorio volumio[2909]: info: Setting Device type: Raspberry PI Dec 16 05:38:02 dormitorio volumio[2909]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::pushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:02 dormitorio volumio[2909]: info: Completed loading Core Plugins Dec 16 05:38:02 dormitorio volumio[2909]: info: Preparing to generate the ALSA configuration file Dec 16 05:38:02 dormitorio volumio[2909]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:38:02 dormitorio volumio[2909]: info: Output device has changed, restarting MPD Dec 16 05:38:02 dormitorio sudo[3064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:02 dormitorio volumio[2909]: info: Output device has changed, restarting Shairport Sync Dec 16 05:38:02 dormitorio sudo[3064]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:02 dormitorio sudo[3064]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:02 dormitorio sudo[3066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:02 dormitorio sudo[3066]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:02 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:38:02 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:02 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:02 dormitorio volumio[2909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:02 dormitorio volumio[2909]: info: ___________ START PLUGINS ___________ Dec 16 05:38:02 dormitorio volumio[2909]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:38:02 dormitorio volumio[2909]: info: Creating MPD Configuration file Dec 16 05:38:02 dormitorio sudo[3074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:02 dormitorio sudo[3074]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:02 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:02 dormitorio volumio[2909]: info: [1734338282304] CoreMusicLibrary::Adding element Media Servers Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:02 dormitorio sudo[3074]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:02 dormitorio sudo[3076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:02 dormitorio sudo[3076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:02 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:38:02 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:02 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:02 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:02 dormitorio volumio[2909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:02 dormitorio volumio[2909]: info: [1734338282420] CoreMusicLibrary::Adding element Last_100 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:02 dormitorio volumio[2909]: info: [1734338282424] CoreMusicLibrary::Adding element Webradio Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:02 dormitorio volumio[2909]: info: Initializing BBC Radios Dec 16 05:38:02 dormitorio sudo[3082]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:38:02 dormitorio sudo[3082]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:02 dormitorio sudo[3082]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: Creating Spotify config file Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:38:02 dormitorio volumio[2909]: info: Volumio Calling Home Dec 16 05:38:02 dormitorio volumio[2909]: info: [squeezelite_mc] Proxy server started on port 38221 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:02 dormitorio volumio[2909]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:38:02 dormitorio volumio[2909]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:38:02 dormitorio volumio[2909]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:38:02 dormitorio volumio[2909]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:38:02 dormitorio volumio[2909]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:38:02 dormitorio volumio[2909]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:38:02 dormitorio volumio[2909]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:38:02 dormitorio volumio[2909]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:38:02 dormitorio volumio[2909]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:38:02 dormitorio volumio[2909]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:38:02 dormitorio volumio[2909]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:38:02 dormitorio volumio[2909]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:38:02 dormitorio volumio[2909]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:38:02 dormitorio volumio[2909]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:38:02 dormitorio volumio[2909]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:38:02 dormitorio volumio[2909]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:38:02 dormitorio volumio[2909]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:38:02 dormitorio volumio[2909]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:02 dormitorio volumio[2909]: info: MPD Permissions set Dec 16 05:38:02 dormitorio volumio[2909]: info: MPD Permissions set Dec 16 05:38:02 dormitorio volumio[2909]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreStateMachine::pushState Dec 16 05:38:02 dormitorio volumio[2909]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:02 dormitorio volumio[2909]: info: [squeezelite_mc] Server discovery started Dec 16 05:38:02 dormitorio volumio[2909]: info: [squeezelite_mc] Player finder started Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:02 dormitorio volumio[2909]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:38:02 dormitorio volumio[2909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:02 dormitorio volumio[2909]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:38:02 dormitorio volumio[2909]: at doSend (dgram.js:692:16) Dec 16 05:38:02 dormitorio volumio[2909]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:38:02 dormitorio volumio[2909]: at afterDns (dgram.js:638:5) Dec 16 05:38:02 dormitorio volumio[2909]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:38:02 dormitorio volumio[2909]: errno: -101, Dec 16 05:38:02 dormitorio volumio[2909]: code: 'ENETUNREACH', Dec 16 05:38:02 dormitorio volumio[2909]: syscall: 'send', Dec 16 05:38:02 dormitorio volumio[2909]: address: '255.255.255.255', Dec 16 05:38:02 dormitorio volumio[2909]: port: 3483 Dec 16 05:38:02 dormitorio volumio[2909]: } Dec 16 05:38:02 dormitorio volumio[2909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:03 dormitorio sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:37 Dec 16 05:38:03 dormitorio sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:03 dormitorio sudo[3114]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:03 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:03 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 16 05:38:03 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:03 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 16 05:38:03 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 16 05:38:03 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:03 dormitorio go-librespot[3120]: Librespot-go daemon starting... Dec 16 05:38:03 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:03 dormitorio sudo[3066]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:03 dormitorio sudo[3076]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:03 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:38:03 dormitorio go-librespot[3120]: time="2024-12-16T05:38:03-03:00" level=info msg="generated new device id: 8a817eaacb595bfc33f3e66999648aa4131bfabb" Dec 16 05:38:03 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:03 dormitorio go-librespot[3120]: time="2024-12-16T05:38:03-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:03 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:03 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:03 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:03 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:03 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:38:03 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Dec 16 05:38:03 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:38:03 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:38:03 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:03 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:03 dormitorio mpd[3094]: Dec 16 05:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:38:03 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:38:05 dormitorio volumio[3139]: info: ------------------------------------------- Dec 16 05:38:05 dormitorio volumio[3139]: info: ----- Volumio3 ---- Dec 16 05:38:05 dormitorio volumio[3139]: info: ------------------------------------------- Dec 16 05:38:05 dormitorio volumio[3139]: info: ----- System startup ---- Dec 16 05:38:05 dormitorio volumio[3139]: info: ------------------------------------------- Dec 16 05:38:06 dormitorio volumio[3139]: info: MYVOLUMIO Environment detected Dec 16 05:38:06 dormitorio volumio[3139]: info: Plugin folders cleanup Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category audio_interface Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category miscellanea Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category music_service Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category plugins.json Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category system_controller Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category user_interface Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning into folder /data/plugins/ Dec 16 05:38:06 dormitorio volumio[3139]: info: Scanning category music_service Dec 16 05:38:06 dormitorio volumio[3139]: info: Plugin folders cleanup completed Dec 16 05:38:06 dormitorio volumio[3139]: info: ------------------------------------------- Dec 16 05:38:06 dormitorio volumio[3139]: info: ----- Core plugins startup ---- Dec 16 05:38:06 dormitorio volumio[3139]: info: ------------------------------------------- Dec 16 05:38:06 dormitorio volumio[3139]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:38:06 dormitorio volumio[3139]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:38:06 dormitorio volumio[3139]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:38:06 dormitorio volumio[3139]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:38:06 dormitorio volumio[3139]: info: Loading plugins from folder /data/plugins/ Dec 16 05:38:06 dormitorio volumio[3139]: info: Loading plugin "system"... Dec 16 05:38:06 dormitorio volumio[3139]: info: Loading plugin "appearance"... Dec 16 05:38:06 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:06 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 16 05:38:06 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:06 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:06 dormitorio go-librespot[3167]: Librespot-go daemon starting... Dec 16 05:38:06 dormitorio go-librespot[3167]: time="2024-12-16T05:38:06-03:00" level=info msg="generated new device id: 4af6e62497013eb274bbefa1a7aa105ddd4a6424" Dec 16 05:38:06 dormitorio go-librespot[3167]: time="2024-12-16T05:38:06-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:06 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:06 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "network"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Refreshing Cached IP Addresses Dec 16 05:38:07 dormitorio sudo[3175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:38:07 dormitorio sudo[3175]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:07 dormitorio sudo[3175]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:07 dormitorio sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:38:07 dormitorio sudo[3177]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:07 dormitorio sudo[3177]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "services"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "alsa_controller"... Dec 16 05:38:07 dormitorio sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:38:07 dormitorio sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:07 dormitorio sudo[3189]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:07 dormitorio volumio[3139]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:38:07 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "wizard"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "networkfs"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Starting Udev Watcher for removable devices Dec 16 05:38:07 dormitorio volumio[3139]: info: Ignoring mount for partition: boot Dec 16 05:38:07 dormitorio volumio[3139]: info: Ignoring mount for partition: volumio Dec 16 05:38:07 dormitorio volumio[3139]: info: Ignoring mount for partition: volumio_data Dec 16 05:38:07 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "upnp"... Dec 16 05:38:07 dormitorio volumio[3139]: info: [1734338287449] Starting Upmpd Daemon Dec 16 05:38:07 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "my_music"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "mpd"... Dec 16 05:38:07 dormitorio volumio[3139]: info: Loading plugin "upnp_browser"... Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "alarm-clock"... Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "airplay_emulation"... Dec 16 05:38:08 dormitorio volumio[3139]: info: Starting Shairport Sync Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "last_100"... Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "webradio"... Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "i2s_dacs"... Dec 16 05:38:08 dormitorio volumio[3139]: info: I2S DAC not set, start Auto-detection Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "volumiodiscovery"... Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** For more information see Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:08 dormitorio volumio[3139]: *** WARNING *** For more information see Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** For more information see Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:08 dormitorio node[3139]: *** WARNING *** For more information see Dec 16 05:38:08 dormitorio volumio[3139]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:38:08 dormitorio volumio[3139]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:38:08 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:08 dormitorio volumio[3139]: info: Loading plugin "spop"... Dec 16 05:38:08 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:08] [connect] Successful connection Dec 16 05:38:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:08 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:09 dormitorio volumio[3139]: info: Loading plugin "squeezelite_mc"... Dec 16 05:38:10 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:10 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 16 05:38:10 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "outputs"... Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "albumart"... Dec 16 05:38:10 dormitorio volumio[3139]: info: Plugin example_plugin is not enabled Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "inputs"... Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "updater_comm"... Dec 16 05:38:10 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:10 dormitorio go-librespot[3210]: Librespot-go daemon starting... Dec 16 05:38:10 dormitorio go-librespot[3210]: time="2024-12-16T05:38:10-03:00" level=info msg="generated new device id: 978046a6a2fe755ee0b5acbd5f7a81b81245b7e1" Dec 16 05:38:10 dormitorio go-librespot[3210]: time="2024-12-16T05:38:10-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:10 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:10 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:10 dormitorio volumio[3139]: info: Plugin mpdemulation is not enabled Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "rest_api"... Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading plugin "websocket"... Dec 16 05:38:10 dormitorio volumio[3139]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:38:10 dormitorio volumio[3139]: info: Loading i18n strings for locale es Dec 16 05:38:10 dormitorio volumio[3139]: Updating browse sources language Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:10 dormitorio volumio[3139]: Forking 3 albumart workers Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::initPlayerControls Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: Express server listening on port 3000 Dec 16 05:38:10 dormitorio volumio[3139]: [Metrics] WebUI: 5s 502.00ms Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreStateMachine::resetVolumioState Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreStateMachine::getcurrentVolume Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:10 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:10 dormitorio volumio[3139]: info: Cannot read play queue from file Dec 16 05:38:10 dormitorio volumio[3139]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:38:10 dormitorio volumio[3139]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:10 dormitorio volumio[3139]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:38:10 dormitorio volumio[3139]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:38:10 dormitorio volumio[3139]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:38:10 dormitorio sudo[3252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:38:10 dormitorio sudo[3252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:11 dormitorio volumio[3139]: Starting albumart workers Dec 16 05:38:11 dormitorio volumio[3139]: Starting albumart workers Dec 16 05:38:11 dormitorio volumio[3139]: Starting albumart workers Dec 16 05:38:11 dormitorio wpa_supplicant[834]: wlan0: Failed to initiate sched scan Dec 16 05:38:13 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:13 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 16 05:38:13 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:13 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:13 dormitorio go-librespot[3278]: Librespot-go daemon starting... Dec 16 05:38:13 dormitorio go-librespot[3278]: time="2024-12-16T05:38:13-03:00" level=info msg="generated new device id: 339f06304296c016b5a15cc2c3c9f1a6d7df40af" Dec 16 05:38:13 dormitorio go-librespot[3278]: time="2024-12-16T05:38:13-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:13 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:13 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:13 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:14 dormitorio sudo[3252]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:14 dormitorio volumio[3139]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::pushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::updateTrackBlock Dec 16 05:38:14 dormitorio volumio[3139]: info: CorePlayQueue::getTrackBlock Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:14 dormitorio volumio[3139]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::pushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::setRandom null Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::pushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:14 dormitorio volumio[3139]: info: Setting Device type: Raspberry PI Dec 16 05:38:14 dormitorio volumio[3139]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreStateMachine::pushState Dec 16 05:38:14 dormitorio volumio[3139]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:14 dormitorio volumio[3139]: info: Completed loading Core Plugins Dec 16 05:38:14 dormitorio volumio[3139]: info: Preparing to generate the ALSA configuration file Dec 16 05:38:14 dormitorio volumio[3139]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:38:14 dormitorio volumio[3139]: info: Output device has changed, restarting MPD Dec 16 05:38:14 dormitorio sudo[3297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:14 dormitorio volumio[3139]: info: Output device has changed, restarting Shairport Sync Dec 16 05:38:14 dormitorio sudo[3297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:14 dormitorio sudo[3297]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:14 dormitorio sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:14 dormitorio sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:14 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:38:14 dormitorio volumio[3139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:14 dormitorio volumio[3139]: info: ___________ START PLUGINS ___________ Dec 16 05:38:14 dormitorio volumio[3139]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:38:14 dormitorio volumio[3139]: info: Creating MPD Configuration file Dec 16 05:38:14 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:14 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:14 dormitorio sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:14 dormitorio volumio[3139]: info: [1734338294628] CoreMusicLibrary::Adding element Media Servers Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:14 dormitorio sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:14 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:14 dormitorio sudo[3307]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:14 dormitorio sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:14 dormitorio sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:14 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:38:14 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:14 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:14 dormitorio volumio[3139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:14 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:14 dormitorio volumio[3139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:14 dormitorio volumio[3139]: info: [1734338294745] CoreMusicLibrary::Adding element Last_100 Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:14 dormitorio volumio[3139]: info: [1734338294749] CoreMusicLibrary::Adding element Webradio Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:14 dormitorio volumio[3139]: info: Initializing BBC Radios Dec 16 05:38:14 dormitorio sudo[3315]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:38:14 dormitorio sudo[3315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:14 dormitorio sudo[3315]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: Creating Spotify config file Dec 16 05:38:14 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:14 dormitorio volumio[3139]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:38:14 dormitorio volumio[3139]: info: Volumio Calling Home Dec 16 05:38:14 dormitorio volumio[3139]: info: [squeezelite_mc] Proxy server started on port 44775 Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:15 dormitorio volumio[3139]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:38:15 dormitorio volumio[3139]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:38:15 dormitorio volumio[3139]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:38:15 dormitorio volumio[3139]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:38:15 dormitorio volumio[3139]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:38:15 dormitorio volumio[3139]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:38:15 dormitorio volumio[3139]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:38:15 dormitorio volumio[3139]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:38:15 dormitorio volumio[3139]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:38:15 dormitorio volumio[3139]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:38:15 dormitorio volumio[3139]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:38:15 dormitorio volumio[3139]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:38:15 dormitorio volumio[3139]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:38:15 dormitorio volumio[3139]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:38:15 dormitorio volumio[3139]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:38:15 dormitorio volumio[3139]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:38:15 dormitorio volumio[3139]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:38:15 dormitorio volumio[3139]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:15 dormitorio volumio[3139]: info: MPD Permissions set Dec 16 05:38:15 dormitorio volumio[3139]: info: MPD Permissions set Dec 16 05:38:15 dormitorio volumio[3139]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreStateMachine::pushState Dec 16 05:38:15 dormitorio volumio[3139]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:15 dormitorio volumio[3139]: info: [squeezelite_mc] Server discovery started Dec 16 05:38:15 dormitorio volumio[3139]: info: [squeezelite_mc] Player finder started Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:15 dormitorio volumio[3139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:15 dormitorio volumio[3139]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:38:15 dormitorio volumio[3139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:15 dormitorio volumio[3139]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:38:15 dormitorio volumio[3139]: at doSend (dgram.js:692:16) Dec 16 05:38:15 dormitorio volumio[3139]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:38:15 dormitorio volumio[3139]: at afterDns (dgram.js:638:5) Dec 16 05:38:15 dormitorio volumio[3139]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:38:15 dormitorio volumio[3139]: errno: -101, Dec 16 05:38:15 dormitorio volumio[3139]: code: 'ENETUNREACH', Dec 16 05:38:15 dormitorio volumio[3139]: syscall: 'send', Dec 16 05:38:15 dormitorio volumio[3139]: address: '255.255.255.255', Dec 16 05:38:15 dormitorio volumio[3139]: port: 3483 Dec 16 05:38:15 dormitorio volumio[3139]: } Dec 16 05:38:15 dormitorio volumio[3139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:15 dormitorio sudo[3347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:37 Dec 16 05:38:15 dormitorio sudo[3347]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:15 dormitorio sudo[3347]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:16 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:16 dormitorio sudo[3309]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:16 dormitorio sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:16 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:38:16 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:16 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:16 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:38:16 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Dec 16 05:38:16 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:38:16 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:38:16 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:16 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:16 dormitorio mpd[3330]: Dec 16 05:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:38:16 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:38:16 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:16 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 16 05:38:16 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:16 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:16 dormitorio go-librespot[3375]: Librespot-go daemon starting... Dec 16 05:38:16 dormitorio go-librespot[3375]: time="2024-12-16T05:38:16-03:00" level=info msg="generated new device id: 554d9e5ebf5097926f2c46e276d7c506451fc6d2" Dec 16 05:38:16 dormitorio go-librespot[3375]: time="2024-12-16T05:38:16-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:16 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:16 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:17 dormitorio volumio[3360]: info: ------------------------------------------- Dec 16 05:38:17 dormitorio volumio[3360]: info: ----- Volumio3 ---- Dec 16 05:38:17 dormitorio volumio[3360]: info: ------------------------------------------- Dec 16 05:38:17 dormitorio volumio[3360]: info: ----- System startup ---- Dec 16 05:38:17 dormitorio volumio[3360]: info: ------------------------------------------- Dec 16 05:38:18 dormitorio volumio[3360]: info: MYVOLUMIO Environment detected Dec 16 05:38:18 dormitorio volumio[3360]: info: Plugin folders cleanup Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category audio_interface Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category miscellanea Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category music_service Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category plugins.json Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category system_controller Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category user_interface Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning into folder /data/plugins/ Dec 16 05:38:18 dormitorio volumio[3360]: info: Scanning category music_service Dec 16 05:38:18 dormitorio volumio[3360]: info: Plugin folders cleanup completed Dec 16 05:38:18 dormitorio volumio[3360]: info: ------------------------------------------- Dec 16 05:38:18 dormitorio volumio[3360]: info: ----- Core plugins startup ---- Dec 16 05:38:18 dormitorio volumio[3360]: info: ------------------------------------------- Dec 16 05:38:18 dormitorio volumio[3360]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:38:18 dormitorio volumio[3360]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:38:18 dormitorio volumio[3360]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:38:18 dormitorio volumio[3360]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:38:18 dormitorio volumio[3360]: info: Loading plugins from folder /data/plugins/ Dec 16 05:38:18 dormitorio volumio[3360]: info: Loading plugin "system"... Dec 16 05:38:18 dormitorio volumio[3360]: info: Loading plugin "appearance"... Dec 16 05:38:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:18 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "network"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Refreshing Cached IP Addresses Dec 16 05:38:19 dormitorio sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:38:19 dormitorio sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:19 dormitorio sudo[3405]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:19 dormitorio sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:38:19 dormitorio sudo[3407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:19 dormitorio sudo[3407]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "services"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "alsa_controller"... Dec 16 05:38:19 dormitorio sudo[3416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:38:19 dormitorio sudo[3416]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:19 dormitorio sudo[3416]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:19 dormitorio volumio[3360]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:38:19 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "wizard"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "networkfs"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Starting Udev Watcher for removable devices Dec 16 05:38:19 dormitorio volumio[3360]: info: Ignoring mount for partition: boot Dec 16 05:38:19 dormitorio volumio[3360]: info: Ignoring mount for partition: volumio Dec 16 05:38:19 dormitorio volumio[3360]: info: Ignoring mount for partition: volumio_data Dec 16 05:38:19 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "upnp"... Dec 16 05:38:19 dormitorio volumio[3360]: info: [1734338299713] Starting Upmpd Daemon Dec 16 05:38:19 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "my_music"... Dec 16 05:38:19 dormitorio volumio[3360]: info: Loading plugin "mpd"... Dec 16 05:38:19 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:19 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 16 05:38:19 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:19 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:19 dormitorio go-librespot[3430]: Librespot-go daemon starting... Dec 16 05:38:19 dormitorio go-librespot[3430]: time="2024-12-16T05:38:19-03:00" level=info msg="generated new device id: daed3921cbf601e01b5cf238fab726f219559631" Dec 16 05:38:19 dormitorio go-librespot[3430]: time="2024-12-16T05:38:19-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:19 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:19 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "upnp_browser"... Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "alarm-clock"... Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "airplay_emulation"... Dec 16 05:38:20 dormitorio volumio[3360]: info: Starting Shairport Sync Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "last_100"... Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "webradio"... Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "i2s_dacs"... Dec 16 05:38:20 dormitorio volumio[3360]: info: I2S DAC not set, start Auto-detection Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "volumiodiscovery"... Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** For more information see Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:20 dormitorio volumio[3360]: *** WARNING *** For more information see Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** For more information see Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:20 dormitorio node[3360]: *** WARNING *** For more information see Dec 16 05:38:20 dormitorio volumio[3360]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:38:20 dormitorio volumio[3360]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:38:20 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:20 dormitorio volumio[3360]: info: Loading plugin "spop"... Dec 16 05:38:21 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:21] [connect] Successful connection Dec 16 05:38:21 dormitorio volumio[3360]: info: Loading plugin "squeezelite_mc"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "outputs"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "albumart"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Plugin example_plugin is not enabled Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "inputs"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "updater_comm"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Plugin mpdemulation is not enabled Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "rest_api"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading plugin "websocket"... Dec 16 05:38:22 dormitorio volumio[3360]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:38:22 dormitorio volumio[3360]: info: Loading i18n strings for locale es Dec 16 05:38:22 dormitorio volumio[3360]: Updating browse sources language Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::initPlayerControls Dec 16 05:38:22 dormitorio volumio[3360]: Forking 3 albumart workers Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: Express server listening on port 3000 Dec 16 05:38:22 dormitorio volumio[3360]: [Metrics] WebUI: 5s 530.78ms Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreStateMachine::resetVolumioState Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreStateMachine::getcurrentVolume Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:22 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:22 dormitorio volumio[3360]: info: Cannot read play queue from file Dec 16 05:38:23 dormitorio volumio[3360]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:38:23 dormitorio volumio[3360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:23 dormitorio volumio[3360]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 16 05:38:23 dormitorio volumio[3360]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 16 05:38:23 dormitorio volumio[3360]: info: Cannot use regular scanning, forcing with ap-force Dec 16 05:38:23 dormitorio sudo[3474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 16 05:38:23 dormitorio sudo[3474]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:23 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:23 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 16 05:38:23 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:23 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:23 dormitorio go-librespot[3476]: Librespot-go daemon starting... Dec 16 05:38:23 dormitorio go-librespot[3476]: time="2024-12-16T05:38:23-03:00" level=info msg="generated new device id: e45ead067026dd364396ffca870dad4fd3a790e3" Dec 16 05:38:23 dormitorio go-librespot[3476]: time="2024-12-16T05:38:23-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:23 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:23 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:23 dormitorio volumio[3360]: Starting albumart workers Dec 16 05:38:23 dormitorio volumio[3360]: Starting albumart workers Dec 16 05:38:23 dormitorio volumio[3360]: Starting albumart workers Dec 16 05:38:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:23 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:26 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:26 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 16 05:38:26 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:26 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:26 dormitorio go-librespot[3511]: Librespot-go daemon starting... Dec 16 05:38:26 dormitorio go-librespot[3511]: time="2024-12-16T05:38:26-03:00" level=info msg="generated new device id: 25a0b1b3e9677e4be4147420227d8a93902009ec" Dec 16 05:38:26 dormitorio go-librespot[3511]: time="2024-12-16T05:38:26-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:26 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:26 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:26 dormitorio sudo[3474]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:26 dormitorio volumio[3360]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::pushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::updateTrackBlock Dec 16 05:38:26 dormitorio volumio[3360]: info: CorePlayQueue::getTrackBlock Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:26 dormitorio volumio[3360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::pushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::setRandom null Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::pushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:26 dormitorio volumio[3360]: info: Setting Device type: Raspberry PI Dec 16 05:38:26 dormitorio volumio[3360]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreStateMachine::pushState Dec 16 05:38:26 dormitorio volumio[3360]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:26 dormitorio volumio[3360]: info: Completed loading Core Plugins Dec 16 05:38:26 dormitorio volumio[3360]: info: Preparing to generate the ALSA configuration file Dec 16 05:38:26 dormitorio volumio[3360]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:38:26 dormitorio volumio[3360]: info: Output device has changed, restarting MPD Dec 16 05:38:26 dormitorio sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:26 dormitorio volumio[3360]: info: Output device has changed, restarting Shairport Sync Dec 16 05:38:26 dormitorio sudo[3526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:26 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:26 dormitorio sudo[3526]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:26 dormitorio sudo[3528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:26 dormitorio sudo[3528]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:26 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:38:26 dormitorio volumio[3360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:26 dormitorio volumio[3360]: info: ___________ START PLUGINS ___________ Dec 16 05:38:26 dormitorio volumio[3360]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:38:26 dormitorio volumio[3360]: info: Creating MPD Configuration file Dec 16 05:38:26 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:26 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:26 dormitorio sudo[3536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:26 dormitorio sudo[3536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:27 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:27 dormitorio volumio[3360]: info: [1734338306971] CoreMusicLibrary::Adding element Media Servers Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:26 dormitorio sudo[3536]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:26 dormitorio sudo[3538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:26 dormitorio sudo[3538]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:27 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:38:27 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:27 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:27 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:27 dormitorio volumio[3360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:27 dormitorio volumio[3360]: info: [1734338307093] CoreMusicLibrary::Adding element Last_100 Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:27 dormitorio volumio[3360]: info: [1734338307097] CoreMusicLibrary::Adding element Webradio Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:27 dormitorio volumio[3360]: info: Initializing BBC Radios Dec 16 05:38:27 dormitorio sudo[3544]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:38:27 dormitorio sudo[3544]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:27 dormitorio sudo[3544]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: Creating Spotify config file Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:38:27 dormitorio volumio[3360]: info: Volumio Calling Home Dec 16 05:38:27 dormitorio volumio[3360]: info: [squeezelite_mc] Proxy server started on port 33711 Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:27 dormitorio volumio[3360]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:38:27 dormitorio volumio[3360]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:38:27 dormitorio volumio[3360]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:38:27 dormitorio volumio[3360]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:38:27 dormitorio volumio[3360]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:38:27 dormitorio volumio[3360]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:38:27 dormitorio volumio[3360]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:38:27 dormitorio volumio[3360]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:38:27 dormitorio volumio[3360]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:38:27 dormitorio volumio[3360]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:38:27 dormitorio volumio[3360]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:38:27 dormitorio volumio[3360]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:38:27 dormitorio volumio[3360]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:38:27 dormitorio volumio[3360]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:38:27 dormitorio volumio[3360]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:38:27 dormitorio volumio[3360]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:38:27 dormitorio volumio[3360]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:38:27 dormitorio volumio[3360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:27 dormitorio volumio[3360]: info: MPD Permissions set Dec 16 05:38:27 dormitorio volumio[3360]: info: MPD Permissions set Dec 16 05:38:27 dormitorio volumio[3360]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreStateMachine::pushState Dec 16 05:38:27 dormitorio volumio[3360]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:27 dormitorio volumio[3360]: info: [squeezelite_mc] Server discovery started Dec 16 05:38:27 dormitorio volumio[3360]: info: [squeezelite_mc] Player finder started Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:27 dormitorio volumio[3360]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:38:27 dormitorio volumio[3360]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:27 dormitorio volumio[3360]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:38:27 dormitorio volumio[3360]: at doSend (dgram.js:692:16) Dec 16 05:38:27 dormitorio volumio[3360]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:38:27 dormitorio volumio[3360]: at afterDns (dgram.js:638:5) Dec 16 05:38:27 dormitorio volumio[3360]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:38:27 dormitorio volumio[3360]: errno: -101, Dec 16 05:38:27 dormitorio volumio[3360]: code: 'ENETUNREACH', Dec 16 05:38:27 dormitorio volumio[3360]: syscall: 'send', Dec 16 05:38:27 dormitorio volumio[3360]: address: '255.255.255.255', Dec 16 05:38:27 dormitorio volumio[3360]: port: 3483 Dec 16 05:38:27 dormitorio volumio[3360]: } Dec 16 05:38:27 dormitorio volumio[3360]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:28 dormitorio sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:37 Dec 16 05:38:28 dormitorio sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:28 dormitorio sudo[3576]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:28 dormitorio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:28 dormitorio sudo[3538]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:28 dormitorio sudo[3528]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:28 dormitorio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 16 05:38:28 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:28 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:28 dormitorio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 16 05:38:28 dormitorio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Dec 16 05:38:28 dormitorio mpd[3559]: Dec 16 05:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 05:38:28 dormitorio systemd[1]: Started dynamicswap service. Dec 16 05:38:28 dormitorio systemd[1]: Stopped Volumio Backend Module. Dec 16 05:38:28 dormitorio systemd[1]: Started Volumio Backend Module. Dec 16 05:38:28 dormitorio systemd[1]: Started Music Player Daemon. Dec 16 05:38:28 dormitorio systemd[1]: dynamicswap.service: Succeeded. Dec 16 05:38:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:28 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:29 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:29 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 16 05:38:29 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:29 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:29 dormitorio go-librespot[3612]: Librespot-go daemon starting... Dec 16 05:38:29 dormitorio go-librespot[3612]: time="2024-12-16T05:38:29-03:00" level=info msg="generated new device id: 2cc03cab49260a72c7d47e890690691e302d4656" Dec 16 05:38:29 dormitorio go-librespot[3612]: time="2024-12-16T05:38:29-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:29 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:29 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:30 dormitorio wpa_supplicant[834]: wlan0: Trying to associate with SSID 'Kain' Dec 16 05:38:30 dormitorio volumio[3592]: info: ------------------------------------------- Dec 16 05:38:30 dormitorio volumio[3592]: info: ----- Volumio3 ---- Dec 16 05:38:30 dormitorio volumio[3592]: info: ------------------------------------------- Dec 16 05:38:30 dormitorio volumio[3592]: info: ----- System startup ---- Dec 16 05:38:30 dormitorio volumio[3592]: info: ------------------------------------------- Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: carrier acquired Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: connected to Access Point `Kain' Dec 16 05:38:30 dormitorio wpa_supplicant[834]: wlan0: Associated with 84:3d:c6:25:4d:61 Dec 16 05:38:30 dormitorio wpa_supplicant[834]: wlan0: CTRL-EVENT-CONNECTED - Connection to 84:3d:c6:25:4d:61 completed [id=0 id_str=] Dec 16 05:38:30 dormitorio wpa_supplicant[834]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 16 05:38:30 dormitorio wpa_supplicant[834]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CL Dec 16 05:38:30 dormitorio dhcpcd[846]: DUID 00:01:00:01:2e:f2:a2:db:b8:27:eb:a1:ee:32 Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: IAID eb:f4:bb:67 Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: adding address fe80::4568:22e2:3517:fc83 Dec 16 05:38:30 dormitorio dhcpcd[846]: ipv6_addaddr1: Permission denied Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: carrier lost Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: carrier acquired Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: IAID eb:f4:bb:67 Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: rebinding lease of 192.168.66.140 Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: NAK: from 192.168.66.1 Dec 16 05:38:30 dormitorio dhcpcd[846]: wlan0: soliciting a DHCP lease Dec 16 05:38:31 dormitorio dhcpcd[846]: wlan0: soliciting an IPv6 router Dec 16 05:38:31 dormitorio volumio[3592]: info: MYVOLUMIO Environment detected Dec 16 05:38:31 dormitorio volumio[3592]: info: Plugin folders cleanup Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning into folder /volumio/app/plugins/ Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category audio_interface Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category miscellanea Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category music_service Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category plugins.json Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category system_controller Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category user_interface Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning into folder /data/plugins/ Dec 16 05:38:31 dormitorio volumio[3592]: info: Scanning category music_service Dec 16 05:38:31 dormitorio volumio[3592]: info: Plugin folders cleanup completed Dec 16 05:38:31 dormitorio volumio[3592]: info: ------------------------------------------- Dec 16 05:38:31 dormitorio volumio[3592]: info: ----- Core plugins startup ---- Dec 16 05:38:31 dormitorio volumio[3592]: info: ------------------------------------------- Dec 16 05:38:31 dormitorio volumio[3592]: info: Loading plugins from folder /volumio/app/plugins/ Dec 16 05:38:31 dormitorio volumio[3592]: info: Adding plugin upnp to MyMusic Plugins Dec 16 05:38:31 dormitorio volumio[3592]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 16 05:38:31 dormitorio volumio[3592]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 16 05:38:31 dormitorio volumio[3592]: info: Loading plugins from folder /data/plugins/ Dec 16 05:38:31 dormitorio volumio[3592]: info: Loading plugin "system"... Dec 16 05:38:31 dormitorio volumio[3592]: info: Loading plugin "appearance"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "network"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Refreshing Cached IP Addresses Dec 16 05:38:32 dormitorio sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 05:38:32 dormitorio sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:32 dormitorio sudo[3654]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:32 dormitorio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 05:38:32 dormitorio sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:32 dormitorio sudo[3656]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "services"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "alsa_controller"... Dec 16 05:38:32 dormitorio sudo[3665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 16 05:38:32 dormitorio sudo[3665]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:32 dormitorio volumio[3592]: warn: Unable to locate the audio output device D50. Please configure a valid output device. Dec 16 05:38:32 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "wizard"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "networkfs"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Starting Udev Watcher for removable devices Dec 16 05:38:32 dormitorio volumio[3592]: info: Ignoring mount for partition: boot Dec 16 05:38:32 dormitorio volumio[3592]: info: Ignoring mount for partition: volumio Dec 16 05:38:32 dormitorio volumio[3592]: info: Ignoring mount for partition: volumio_data Dec 16 05:38:32 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "volumio_command_line_client"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "upnp"... Dec 16 05:38:32 dormitorio volumio[3592]: info: [1734338312265] Starting Upmpd Daemon Dec 16 05:38:32 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "my_music"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "mpd"... Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "upnp_browser"... Dec 16 05:38:32 dormitorio dhcpcd[846]: wlan0: offered 192.168.66.140 from 192.168.66.1 Dec 16 05:38:32 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:32 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 16 05:38:32 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:32 dormitorio volumio[3592]: info: Loading plugin "alarm-clock"... Dec 16 05:38:32 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:32 dormitorio go-librespot[3717]: Librespot-go daemon starting... Dec 16 05:38:32 dormitorio dhcpcd[846]: wlan0: probing address 192.168.66.140/24 Dec 16 05:38:32 dormitorio go-librespot[3717]: time="2024-12-16T05:38:32-03:00" level=info msg="generated new device id: 3230cc212a404e04b7371bd3a1c914619a4bbd2e" Dec 16 05:38:32 dormitorio go-librespot[3717]: time="2024-12-16T05:38:32-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:32 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:32 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "airplay_emulation"... Dec 16 05:38:33 dormitorio volumio[3592]: info: Starting Shairport Sync Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "last_100"... Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "webradio"... Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "i2s_dacs"... Dec 16 05:38:33 dormitorio volumio[3592]: info: I2S DAC not set, start Auto-detection Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "volumiodiscovery"... Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** For more information see Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:33 dormitorio volumio[3592]: *** WARNING *** For more information see Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** For more information see Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 16 05:38:33 dormitorio node[3592]: *** WARNING *** For more information see Dec 16 05:38:33 dormitorio volumio[3592]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 16 05:38:33 dormitorio volumio[3592]: info: Discovery: Started advertising with name: Dormitorio Dec 16 05:38:33 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 16 05:38:33 dormitorio volumio[3592]: info: Loading plugin "spop"... Dec 16 05:38:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Fetching time from Volumio... Dec 16 05:38:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Date not found in response Dec 16 05:38:33 dormitorio volumio-time-update[579]: volumio-time-update-util: Retrying in 5 seconds... Dec 16 05:38:34 dormitorio volumio[3592]: info: Loading plugin "squeezelite_mc"... Dec 16 05:38:34 dormitorio volumio[3592]: info: Loading plugin "outputs"... Dec 16 05:38:34 dormitorio volumio[3592]: info: Loading plugin "albumart"... Dec 16 05:38:34 dormitorio volumio[3592]: info: Plugin example_plugin is not enabled Dec 16 05:38:34 dormitorio volumio[3592]: info: Loading plugin "inputs"... Dec 16 05:38:34 dormitorio volumio[3592]: info: Loading plugin "updater_comm"... Dec 16 05:38:35 dormitorio volumio[3592]: info: Plugin mpdemulation is not enabled Dec 16 05:38:35 dormitorio volumio[3592]: info: Loading plugin "rest_api"... Dec 16 05:38:35 dormitorio volumio[3592]: info: Loading plugin "websocket"... Dec 16 05:38:35 dormitorio volumio[3592]: info: Starting Socket.io Server version 2.3.0 Dec 16 05:38:35 dormitorio volumio[3592]: info: Loading i18n strings for locale es Dec 16 05:38:35 dormitorio volumio[3592]: Updating browse sources language Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:35 dormitorio volumio[3592]: Forking 3 albumart workers Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::initPlayerControls Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:35 dormitorio volumio[3592]: Express server listening on port 3000 Dec 16 05:38:35 dormitorio volumio[3592]: [Metrics] WebUI: 5s 611.29ms Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::resetVolumioState Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::getcurrentVolume Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:35 dormitorio volumio[3592]: info: Cannot read play queue from file Dec 16 05:38:35 dormitorio volumio[3592]: info: Volumio Network Manager: Network status updated: 0 Dec 16 05:38:35 dormitorio volumio[3592]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:35 dormitorio volumio[3592]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::pushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::updateTrackBlock Dec 16 05:38:35 dormitorio volumio[3592]: info: CorePlayQueue::getTrackBlock Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:35 dormitorio volumio[3592]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:35 dormitorio volumio[3592]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::pushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::setRepeat null single undefined Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::pushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::setRandom null Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreStateMachine::pushState Dec 16 05:38:35 dormitorio volumio[3592]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:35 dormitorio volumio[3592]: info: Setting Device type: Raspberry PI Dec 16 05:38:35 dormitorio volumio[3592]: info: Completed loading Core Plugins Dec 16 05:38:35 dormitorio volumio[3592]: info: Preparing to generate the ALSA configuration file Dec 16 05:38:35 dormitorio volumio[3592]: info: Asound.conf file unchanged, so no further update is needed Dec 16 05:38:35 dormitorio volumio[3592]: info: Output device has changed, restarting MPD Dec 16 05:38:35 dormitorio sudo[3665]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:35 dormitorio volumio[3592]: info: Output device has changed, restarting Shairport Sync Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:35 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:35 dormitorio sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:35 dormitorio sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:35 dormitorio sudo[3771]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:35 dormitorio sudo[3773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:35 dormitorio sudo[3773]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:35 dormitorio systemd[1]: Stopping Music Player Daemon... Dec 16 05:38:35 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:35 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:35 dormitorio volumio[3592]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:35 dormitorio volumio[3592]: info: ___________ START PLUGINS ___________ Dec 16 05:38:35 dormitorio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 16 05:38:35 dormitorio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 16 05:38:35 dormitorio systemd[1]: Stopped go-librespot Daemon. Dec 16 05:38:36 dormitorio volumio[3592]: info: ControllerMpd::onStart: Initializing MPD Dec 16 05:38:36 dormitorio volumio[3592]: info: Creating MPD Configuration file Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:36 dormitorio sudo[3781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 16 05:38:36 dormitorio sudo[3781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:36 dormitorio systemd[1]: Started go-librespot Daemon. Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:36 dormitorio volumio[3592]: info: [1734338316056] CoreMusicLibrary::Adding element Media Servers Dec 16 05:38:36 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:36 dormitorio go-librespot[3779]: Librespot-go daemon starting... Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:36 dormitorio sudo[3781]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:36 dormitorio go-librespot[3779]: time="2024-12-16T05:38:36-03:00" level=info msg="generated new device id: b1ac7fa9fa9c867d08b35114bf69d46b0da4650e" Dec 16 05:38:36 dormitorio sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 16 05:38:36 dormitorio go-librespot[3779]: time="2024-12-16T05:38:36-03:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 16 05:38:36 dormitorio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 16 05:38:36 dormitorio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 05:38:36 dormitorio sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:36 dormitorio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 16 05:38:36 dormitorio systemd[1]: mpd.service: Succeeded. Dec 16 05:38:36 dormitorio systemd[1]: Stopped Music Player Daemon. Dec 16 05:38:36 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:36] [connect] Successful connection Dec 16 05:38:36 dormitorio systemd[1]: Starting Music Player Daemon... Dec 16 05:38:36 dormitorio volumio[3592]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:36 dormitorio volumio[3592]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:36 dormitorio volumio[3592]: info: [1734338316312] CoreMusicLibrary::Adding element Last_100 Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 16 05:38:36 dormitorio volumio[3592]: info: [1734338316317] CoreMusicLibrary::Adding element Webradio Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:36 dormitorio volumio[3592]: info: Initializing BBC Radios Dec 16 05:38:36 dormitorio volumio[3592]: Starting albumart workers Dec 16 05:38:36 dormitorio sudo[3796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 16 05:38:36 dormitorio sudo[3796]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:36 dormitorio sudo[3796]: pam_unix(sudo:session): session closed for user root Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 05:38:36 dormitorio volumio[3592]: info: Creating Spotify config file Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:36 dormitorio volumio[3592]: info: [squeezelite_mc] Starting proxy server... Dec 16 05:38:36 dormitorio volumio[3592]: info: Volumio Calling Home Dec 16 05:38:36 dormitorio volumio[3592]: Starting albumart workers Dec 16 05:38:36 dormitorio volumio[3592]: Starting albumart workers Dec 16 05:38:36 dormitorio volumio[3592]: info: [squeezelite_mc] Proxy server started on port 46349 Dec 16 05:38:36 dormitorio volumio[3592]: info: CoreCommandRouter::volumioRetrievevolume Dec 16 05:38:36 dormitorio volumio[3592]: Unhandled rejection Error: No sockets available, cannot start. Dec 16 05:38:36 dormitorio volumio[3592]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 16 05:38:36 dormitorio volumio[3592]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 16 05:38:36 dormitorio volumio[3592]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 16 05:38:36 dormitorio volumio[3592]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 16 05:38:36 dormitorio volumio[3592]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 16 05:38:36 dormitorio volumio[3592]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 16 05:38:36 dormitorio volumio[3592]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 16 05:38:36 dormitorio volumio[3592]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 16 05:38:36 dormitorio volumio[3592]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 16 05:38:36 dormitorio volumio[3592]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 16 05:38:36 dormitorio volumio[3592]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 16 05:38:36 dormitorio volumio[3592]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 16 05:38:36 dormitorio volumio[3592]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 16 05:38:36 dormitorio volumio[3592]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 16 05:38:36 dormitorio volumio[3592]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 16 05:38:36 dormitorio volumio[3592]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 16 05:38:36 dormitorio volumio[3592]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 16 05:38:36 dormitorio volumio[3592]: info: MPD Permissions set Dec 16 05:38:36 dormitorio volumio[3592]: info: MPD Permissions set Dec 16 05:38:36 dormitorio volumio-remote-updater[607]: [2024-12-16 05:38:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1734338316 101 Dec 16 05:38:36 dormitorio volumio[3592]: 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: 1 Dec 16 05:38:37 dormitorio volumio[3592]: info: VolumeController:: Volume=undefined Mute =false Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreStateMachine::pushState Dec 16 05:38:37 dormitorio volumio[3592]: info: CorePlayQueue::getTrack 0 Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreCommandRouter::volumioPushState Dec 16 05:38:37 dormitorio volumio[3592]: info: [squeezelite_mc] Server discovery started Dec 16 05:38:37 dormitorio volumio[3592]: info: [squeezelite_mc] Player finder started Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 05:38:37 dormitorio volumio[3592]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 05:38:37 dormitorio volumio[3592]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 16 05:38:37 dormitorio volumio[3592]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:37 dormitorio volumio[3592]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 16 05:38:37 dormitorio volumio[3592]: at doSend (dgram.js:692:16) Dec 16 05:38:37 dormitorio volumio[3592]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Dec 16 05:38:37 dormitorio volumio[3592]: at afterDns (dgram.js:638:5) Dec 16 05:38:37 dormitorio volumio[3592]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Dec 16 05:38:37 dormitorio volumio[3592]: errno: -101, Dec 16 05:38:37 dormitorio volumio[3592]: code: 'ENETUNREACH', Dec 16 05:38:37 dormitorio volumio[3592]: syscall: 'send', Dec 16 05:38:37 dormitorio volumio[3592]: address: '255.255.255.255', Dec 16 05:38:37 dormitorio volumio[3592]: port: 3483 Dec 16 05:38:37 dormitorio volumio[3592]: } Dec 16 05:38:37 dormitorio volumio[3592]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 05:38:37 dormitorio sudo[3852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-16 05:37 Dec 16 05:38:37 dormitorio sudo[3852]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 05:38:37 dormitorio dhcpcd[846]: wlan0: leased 192.168.66.140 for 86400 seconds Dec 16 05:38:37 dormitorio avahi-daemon[563]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.66.140. Dec 16 05:38:37 dormitorio avahi-daemon[563]: New relevant interface wlan0.IPv4 for mDNS. Dec 16 05:38:37 dormitorio dhcpcd[846]: wlan0: adding route to 192.168.66.0/24 Dec 16 05:38:37 dormitorio avahi-daemon[563]: Registering new address record for 192.168.66.140 on wlan0.IPv4. Dec 16 05:38:37 dormitorio dhcpcd[846]: wlan0: adding default route via 192.168.66.1 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"