-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Thu 2024-06-27 03:50:01 CEST. -- Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:00 marius volumio[10771]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:00 marius volumio[10771]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:00 marius volumio[10771]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:00 marius volumio[10771]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:00 marius volumio[10771]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:00 marius volumio[10771]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:00 marius volumio[10771]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:00 marius volumio[10771]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:00 marius volumio[10771]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:00 marius volumio[10771]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:00 marius volumio[10771]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:00 marius volumio[10771]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:00 marius volumio[10771]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:00 marius volumio[10771]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:00 marius volumio[10771]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:00 marius volumio[10771]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:00 marius volumio[10771]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:00 marius volumio[10771]: info: MPD Permissions set Jun 27 03:49:00 marius volumio[10771]: info: MPD Permissions set Jun 27 03:49:00 marius volumio[10771]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:00 marius volumio[10771]: info: CoreStateMachine::pushState Jun 27 03:49:00 marius volumio[10771]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:00 marius volumio[10771]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:00 marius volumio[10771]: info: CoreStateMachine::pushState Jun 27 03:49:00 marius volumio[10771]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:00 marius volumio[10771]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:00 marius volumio[10771]: info: [squeezelite_mc] Player finder started Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:00 marius volumio[10771]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:00 marius volumio[10771]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:00 marius volumio[10771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:00 marius volumio[10771]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:00 marius volumio[10771]: at doSend (dgram.js:692:16) Jun 27 03:49:00 marius volumio[10771]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:00 marius volumio[10771]: at afterDns (dgram.js:638:5) Jun 27 03:49:00 marius volumio[10771]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:00 marius volumio[10771]: errno: -101, Jun 27 03:49:00 marius volumio[10771]: code: 'ENETUNREACH', Jun 27 03:49:00 marius volumio[10771]: syscall: 'send', Jun 27 03:49:00 marius volumio[10771]: address: '255.255.255.255', Jun 27 03:49:00 marius volumio[10771]: port: 3483 Jun 27 03:49:00 marius volumio[10771]: } Jun 27 03:49:00 marius volumio[10771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:00 marius sudo[10924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:00 marius sudo[10924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:00 marius sudo[10924]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:01 marius sudo[10935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:01 marius sudo[10935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:01 marius mpd[10877]: Jun 27 03:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:01 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:01 marius sudo[10853]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:01 marius sudo[10863]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:01 marius sudo[10935]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:01 marius volumio-remote-updater[656]: [2024-06-27 03:49:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:01 marius volumio-remote-updater[656]: [2024-06-27 03:49:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:01 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:01 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:01 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:01 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:01 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:01 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 53. Jun 27 03:49:02 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:02 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:02 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:02 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:02 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="CabDentaireSTURZ" Jun 27 03:49:02 marius wpa_supplicant[902]: wlan0: Trying to associate with SSID 'CabDentaireSTURZ' Jun 27 03:49:03 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 27 03:49:03 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="CabDentaireSTURZ" auth_failures=2 duration=23 reason=CONN_FAILED Jun 27 03:49:03 marius volumio[10951]: info: ------------------------------------------- Jun 27 03:49:03 marius volumio[10951]: info: ----- Volumio3 ---- Jun 27 03:49:03 marius volumio[10951]: info: ------------------------------------------- Jun 27 03:49:03 marius volumio[10951]: info: ----- System startup ---- Jun 27 03:49:03 marius volumio[10951]: info: ------------------------------------------- Jun 27 03:49:04 marius volumio[10951]: info: MYVOLUMIO Environment detected Jun 27 03:49:04 marius volumio[10951]: info: Plugin folders cleanup Jun 27 03:49:04 marius volumio[10951]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:04 marius volumio[10951]: info: Scanning category audio_interface Jun 27 03:49:04 marius volumio[10951]: info: Scanning category miscellanea Jun 27 03:49:04 marius volumio[10951]: info: Scanning category music_service Jun 27 03:49:04 marius volumio[10951]: info: Scanning category plugins.json Jun 27 03:49:04 marius volumio[10951]: info: Scanning category system_controller Jun 27 03:49:04 marius volumio[10951]: info: Scanning category user_interface Jun 27 03:49:04 marius volumio[10951]: info: Scanning into folder /data/plugins/ Jun 27 03:49:04 marius volumio[10951]: info: Scanning category music_service Jun 27 03:49:04 marius volumio[10951]: info: Plugin folders cleanup completed Jun 27 03:49:04 marius volumio[10951]: info: ------------------------------------------- Jun 27 03:49:04 marius volumio[10951]: info: ----- Core plugins startup ---- Jun 27 03:49:04 marius volumio[10951]: info: ------------------------------------------- Jun 27 03:49:04 marius volumio[10951]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:04 marius volumio[10951]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:04 marius volumio[10951]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:04 marius volumio[10951]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:04 marius volumio[10951]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:04 marius volumio[10951]: info: Loading plugin "system"... Jun 27 03:49:04 marius volumio[10951]: info: Loading plugin "appearance"... Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "network"... Jun 27 03:49:05 marius volumio[10951]: info: Refreshing Cached IP Addresses Jun 27 03:49:05 marius sudo[10977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:05 marius sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:05 marius sudo[10977]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "services"... Jun 27 03:49:05 marius sudo[10979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:05 marius sudo[10979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "alsa_controller"... Jun 27 03:49:05 marius sudo[10979]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:05 marius sudo[10982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:05 marius sudo[10982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:05 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "wizard"... Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "networkfs"... Jun 27 03:49:05 marius volumio[10951]: info: Starting Udev Watcher for removable devices Jun 27 03:49:05 marius volumio[10951]: info: Ignoring mount for partition: boot Jun 27 03:49:05 marius volumio[10951]: info: Ignoring mount for partition: volumio Jun 27 03:49:05 marius volumio[10951]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:05 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "upnp"... Jun 27 03:49:05 marius volumio[10951]: info: [1719452945989] Starting Upmpd Daemon Jun 27 03:49:05 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:05 marius volumio[10951]: info: Loading plugin "my_music"... Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "mpd"... Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "upnp_browser"... Jun 27 03:49:06 marius sudo[10982]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "alarm-clock"... Jun 27 03:49:06 marius volumio-remote-updater[656]: [2024-06-27 03:49:06] [connect] Successful connection Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:06 marius volumio[10951]: info: Starting Shairport Sync Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "last_100"... Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "webradio"... Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:06 marius volumio[10951]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** For more information see Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:06 marius node[10951]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:06 marius volumio[10951]: *** WARNING *** For more information see Jun 27 03:49:06 marius node[10951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:06 marius node[10951]: *** WARNING *** For more information see Jun 27 03:49:06 marius node[10951]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:06 marius node[10951]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:06 marius node[10951]: *** WARNING *** For more information see Jun 27 03:49:06 marius volumio[10951]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:06 marius volumio[10951]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:07 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:07 marius volumio[10951]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:07 marius volumio[10951]: info: Loading plugin "outputs"... Jun 27 03:49:07 marius volumio[10951]: info: Loading plugin "albumart"... Jun 27 03:49:07 marius volumio[10951]: info: Plugin example_plugin is not enabled Jun 27 03:49:07 marius volumio[10951]: info: Loading plugin "inputs"... Jun 27 03:49:07 marius volumio[10951]: info: Loading plugin "updater_comm"... Jun 27 03:49:08 marius volumio[10951]: info: Plugin mpdemulation is not enabled Jun 27 03:49:08 marius volumio[10951]: info: Loading plugin "rest_api"... Jun 27 03:49:08 marius volumio[10951]: info: Loading plugin "websocket"... Jun 27 03:49:08 marius volumio[10951]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:08 marius volumio[10951]: info: Loading i18n strings for locale fr Jun 27 03:49:08 marius volumio[10951]: Updating browse sources language Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:08 marius volumio[10951]: Forking 3 albumart workers Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:08 marius volumio[10951]: Express server listening on port 3000 Jun 27 03:49:08 marius volumio[10951]: [Metrics] WebUI: 5s 42.72ms Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:08 marius volumio-remote-updater[656]: [2024-06-27 03:49:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452946 101 Jun 27 03:49:08 marius volumio[10951]: 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 Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: Reloading queue from file Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::pushState Jun 27 03:49:08 marius volumio[10951]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::setRandom null Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::pushState Jun 27 03:49:08 marius volumio[10951]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:08 marius volumio[10951]: info: Setting Device type: Raspberry PI Jun 27 03:49:08 marius volumio[10951]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::pushState Jun 27 03:49:08 marius volumio[10951]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:08 marius volumio[10951]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:08 marius volumio[10951]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:08 marius volumio[10951]: info: Completed loading Core Plugins Jun 27 03:49:08 marius volumio[10951]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:08 marius volumio[10951]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:08 marius volumio[10951]: info: Output device has changed, restarting MPD Jun 27 03:49:08 marius volumio[10951]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:08 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:08 marius sudo[11031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:08 marius sudo[11031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:08 marius sudo[11031]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:08 marius sudo[11033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:08 marius sudo[11033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:08 marius volumio[10951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:08 marius volumio[10951]: info: ___________ START PLUGINS ___________ Jun 27 03:49:08 marius volumio[10951]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:08 marius volumio[10951]: info: Creating MPD Configuration file Jun 27 03:49:08 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:09 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:09 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:09 marius volumio[10951]: info: [1719452949023] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:09 marius sudo[11041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:09 marius sudo[11041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:09 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:09 marius sudo[11039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:09 marius sudo[11039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:09 marius sudo[11039]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:09 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:09 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:09 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:09 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:09 marius volumio[10951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:09 marius sudo[11047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:09 marius sudo[11047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:09 marius sudo[11047]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:09 marius volumio[10951]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:09 marius volumio[10951]: info: [1719452949326] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:09 marius volumio[10951]: info: [1719452949332] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:09 marius volumio[10951]: info: Initializing BBC Radios Jun 27 03:49:09 marius volumio[10951]: Starting albumart workers Jun 27 03:49:09 marius volumio[10951]: Starting albumart workers Jun 27 03:49:09 marius volumio[10951]: Starting albumart workers Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:09 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:09 marius volumio[10951]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:09 marius volumio[10951]: info: Volumio Calling Home Jun 27 03:49:09 marius volumio[10951]: info: [squeezelite_mc] Proxy server started on port 39465 Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:10 marius volumio[10951]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:10 marius volumio[10951]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:10 marius volumio[10951]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:10 marius volumio[10951]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:10 marius volumio[10951]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:10 marius volumio[10951]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:10 marius volumio[10951]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:10 marius volumio[10951]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:10 marius volumio[10951]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:10 marius volumio[10951]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:10 marius volumio[10951]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:10 marius volumio[10951]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:10 marius volumio[10951]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:10 marius volumio[10951]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:10 marius volumio[10951]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:10 marius volumio[10951]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:10 marius volumio[10951]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:10 marius volumio[10951]: info: MPD Permissions set Jun 27 03:49:10 marius volumio[10951]: info: MPD Permissions set Jun 27 03:49:10 marius volumio[10951]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:10 marius volumio[10951]: info: CoreStateMachine::pushState Jun 27 03:49:10 marius volumio[10951]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:10 marius volumio[10951]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:10 marius volumio[10951]: info: CoreStateMachine::pushState Jun 27 03:49:10 marius volumio[10951]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:10 marius volumio[10951]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:10 marius volumio[10951]: info: [squeezelite_mc] Player finder started Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:10 marius volumio[10951]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:10 marius volumio[10951]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:10 marius volumio[10951]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:10 marius volumio[10951]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:10 marius volumio[10951]: at doSend (dgram.js:692:16) Jun 27 03:49:10 marius volumio[10951]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:10 marius volumio[10951]: at afterDns (dgram.js:638:5) Jun 27 03:49:10 marius volumio[10951]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:10 marius volumio[10951]: errno: -101, Jun 27 03:49:10 marius volumio[10951]: code: 'ENETUNREACH', Jun 27 03:49:10 marius volumio[10951]: syscall: 'send', Jun 27 03:49:10 marius volumio[10951]: address: '255.255.255.255', Jun 27 03:49:10 marius volumio[10951]: port: 3483 Jun 27 03:49:10 marius volumio[10951]: } Jun 27 03:49:10 marius volumio[10951]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:10 marius sudo[11104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:10 marius sudo[11104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:10 marius sudo[11104]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:11 marius mpd[11053]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:11 marius mpd[11053]: output: No 'audio_output' defined in config file Jun 27 03:49:11 marius mpd[11053]: output: Successfully detected a sndio audio device Jun 27 03:49:11 marius mpd[11053]: zeroconf: No global port, disabling zeroconf Jun 27 03:49:11 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:11 marius sudo[11041]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:11 marius sudo[11033]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:11 marius sudo[11117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:11 marius sudo[11117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:11 marius sudo[11117]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:11 marius volumio-remote-updater[656]: [2024-06-27 03:49:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:11 marius volumio-remote-updater[656]: [2024-06-27 03:49:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:11 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:11 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:11 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:11 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:11 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:11 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 54. Jun 27 03:49:11 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:11 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:11 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:11 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:13 marius volumio[11131]: info: ------------------------------------------- Jun 27 03:49:13 marius volumio[11131]: info: ----- Volumio3 ---- Jun 27 03:49:13 marius volumio[11131]: info: ------------------------------------------- Jun 27 03:49:13 marius volumio[11131]: info: ----- System startup ---- Jun 27 03:49:13 marius volumio[11131]: info: ------------------------------------------- Jun 27 03:49:14 marius volumio[11131]: info: MYVOLUMIO Environment detected Jun 27 03:49:14 marius volumio[11131]: info: Plugin folders cleanup Jun 27 03:49:14 marius volumio[11131]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:14 marius volumio[11131]: info: Scanning category audio_interface Jun 27 03:49:14 marius volumio[11131]: info: Scanning category miscellanea Jun 27 03:49:14 marius volumio[11131]: info: Scanning category music_service Jun 27 03:49:14 marius volumio[11131]: info: Scanning category plugins.json Jun 27 03:49:14 marius volumio[11131]: info: Scanning category system_controller Jun 27 03:49:14 marius volumio[11131]: info: Scanning category user_interface Jun 27 03:49:14 marius volumio[11131]: info: Scanning into folder /data/plugins/ Jun 27 03:49:14 marius volumio[11131]: info: Scanning category music_service Jun 27 03:49:14 marius volumio[11131]: info: Plugin folders cleanup completed Jun 27 03:49:14 marius volumio[11131]: info: ------------------------------------------- Jun 27 03:49:14 marius volumio[11131]: info: ----- Core plugins startup ---- Jun 27 03:49:14 marius volumio[11131]: info: ------------------------------------------- Jun 27 03:49:14 marius volumio[11131]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:14 marius volumio[11131]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:14 marius volumio[11131]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:14 marius volumio[11131]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:14 marius volumio[11131]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:14 marius volumio[11131]: info: Loading plugin "system"... Jun 27 03:49:14 marius volumio[11131]: info: Loading plugin "appearance"... Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "network"... Jun 27 03:49:15 marius volumio[11131]: info: Refreshing Cached IP Addresses Jun 27 03:49:15 marius sudo[11157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:15 marius sudo[11157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:15 marius sudo[11157]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "services"... Jun 27 03:49:15 marius sudo[11159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:15 marius sudo[11159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "alsa_controller"... Jun 27 03:49:15 marius sudo[11159]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:15 marius sudo[11162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:15 marius sudo[11162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:15 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "wizard"... Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "networkfs"... Jun 27 03:49:15 marius volumio[11131]: info: Starting Udev Watcher for removable devices Jun 27 03:49:15 marius volumio[11131]: info: Ignoring mount for partition: boot Jun 27 03:49:15 marius volumio[11131]: info: Ignoring mount for partition: volumio Jun 27 03:49:15 marius volumio[11131]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:15 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "upnp"... Jun 27 03:49:15 marius volumio[11131]: info: [1719452955920] Starting Upmpd Daemon Jun 27 03:49:15 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "my_music"... Jun 27 03:49:15 marius volumio[11131]: info: Loading plugin "mpd"... Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "upnp_browser"... Jun 27 03:49:16 marius sudo[11162]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "alarm-clock"... Jun 27 03:49:16 marius volumio-remote-updater[656]: [2024-06-27 03:49:16] [connect] Successful connection Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:16 marius volumio[11131]: info: Starting Shairport Sync Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "last_100"... Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "webradio"... Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** For more information see Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:16 marius node[11131]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:16 marius volumio[11131]: *** WARNING *** For more information see Jun 27 03:49:16 marius node[11131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:16 marius node[11131]: *** WARNING *** For more information see Jun 27 03:49:16 marius node[11131]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:16 marius node[11131]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:16 marius node[11131]: *** WARNING *** For more information see Jun 27 03:49:16 marius volumio[11131]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:16 marius volumio[11131]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:16 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:16 marius volumio[11131]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:17 marius volumio[11131]: info: Loading plugin "outputs"... Jun 27 03:49:17 marius volumio[11131]: info: Loading plugin "albumart"... Jun 27 03:49:17 marius volumio[11131]: info: Plugin example_plugin is not enabled Jun 27 03:49:17 marius volumio[11131]: info: Loading plugin "inputs"... Jun 27 03:49:17 marius volumio[11131]: info: Loading plugin "updater_comm"... Jun 27 03:49:18 marius volumio[11131]: info: Plugin mpdemulation is not enabled Jun 27 03:49:18 marius volumio[11131]: info: Loading plugin "rest_api"... Jun 27 03:49:18 marius volumio[11131]: info: Loading plugin "websocket"... Jun 27 03:49:18 marius volumio[11131]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:18 marius volumio[11131]: info: Loading i18n strings for locale fr Jun 27 03:49:18 marius volumio[11131]: Updating browse sources language Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:18 marius volumio[11131]: Forking 3 albumart workers Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:18 marius volumio[11131]: Express server listening on port 3000 Jun 27 03:49:18 marius volumio[11131]: [Metrics] WebUI: 5s 57.73ms Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:18 marius volumio-remote-updater[656]: [2024-06-27 03:49:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452956 101 Jun 27 03:49:18 marius volumio[11131]: 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 Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: Reloading queue from file Jun 27 03:49:18 marius volumio[11131]: info: Setting Device type: Raspberry PI Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::pushState Jun 27 03:49:18 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::setRandom null Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::pushState Jun 27 03:49:18 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:18 marius volumio[11131]: info: Completed loading Core Plugins Jun 27 03:49:18 marius volumio[11131]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:18 marius volumio[11131]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::pushState Jun 27 03:49:18 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:18 marius volumio[11131]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:18 marius volumio[11131]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:18 marius volumio[11131]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:18 marius volumio[11131]: info: Output device has changed, restarting MPD Jun 27 03:49:18 marius volumio[11131]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:18 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:18 marius sudo[11210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:18 marius sudo[11210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:18 marius sudo[11210]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:18 marius sudo[11212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:18 marius sudo[11212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:18 marius volumio[11131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:18 marius volumio[11131]: info: ___________ START PLUGINS ___________ Jun 27 03:49:18 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:18 marius volumio[11131]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:18 marius volumio[11131]: info: Creating MPD Configuration file Jun 27 03:49:18 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:18 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:19 marius volumio[11131]: info: [1719452959022] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:19 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:19 marius sudo[11222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:19 marius sudo[11222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:19 marius sudo[11220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:19 marius sudo[11220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:19 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:19 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:19 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:19 marius sudo[11220]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:19 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:19 marius volumio[11131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:19 marius sudo[11225]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:19 marius sudo[11225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:19 marius sudo[11225]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:19 marius volumio[11131]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:19 marius volumio[11131]: info: [1719452959299] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:19 marius volumio[11131]: info: [1719452959304] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:19 marius volumio[11131]: info: Initializing BBC Radios Jun 27 03:49:19 marius volumio[11131]: Starting albumart workers Jun 27 03:49:19 marius volumio[11131]: Starting albumart workers Jun 27 03:49:19 marius volumio[11131]: Starting albumart workers Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:19 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:19 marius volumio[11131]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:19 marius volumio[11131]: info: Volumio Calling Home Jun 27 03:49:19 marius volumio[11131]: info: [squeezelite_mc] Proxy server started on port 34727 Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:20 marius volumio[11131]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:20 marius volumio[11131]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:20 marius volumio[11131]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:20 marius volumio[11131]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:20 marius volumio[11131]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:20 marius volumio[11131]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:20 marius volumio[11131]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:20 marius volumio[11131]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:20 marius volumio[11131]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:20 marius volumio[11131]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:20 marius volumio[11131]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:20 marius volumio[11131]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:20 marius volumio[11131]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:20 marius volumio[11131]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:20 marius volumio[11131]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:20 marius volumio[11131]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:20 marius volumio[11131]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:20 marius volumio[11131]: info: MPD Permissions set Jun 27 03:49:20 marius volumio[11131]: info: MPD Permissions set Jun 27 03:49:20 marius volumio[11131]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:20 marius volumio[11131]: info: CoreStateMachine::pushState Jun 27 03:49:20 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:20 marius volumio[11131]: info: Starting Shairport Sync Jun 27 03:49:20 marius volumio[11131]: info: Starting Shairport Sync Jun 27 03:49:20 marius volumio[11131]: info: Starting Shairport Sync Jun 27 03:49:20 marius sudo[11275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 03:49:20 marius sudo[11275]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:20 marius sudo[11281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 03:49:20 marius sudo[11281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:20 marius systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 03:49:20 marius systemd[1]: shairport-sync.service: Succeeded. Jun 27 03:49:20 marius systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 03:49:20 marius sudo[11289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 03:49:20 marius sudo[11289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::volumioGetState Jun 27 03:49:20 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:20 marius volumio[11131]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:20 marius volumio[11131]: info: CoreStateMachine::pushState Jun 27 03:49:20 marius volumio[11131]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:20 marius volumio[11131]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:20 marius volumio[11131]: info: [squeezelite_mc] Player finder started Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 03:49:20 marius sudo[11275]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:20 marius volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:20 marius volumio[11131]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:20 marius volumio[11131]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:20 marius systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 03:49:20 marius systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 27 03:49:20 marius systemd[1]: shairport-sync.service: Succeeded. Jun 27 03:49:20 marius volumio[11131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:20 marius systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 03:49:20 marius volumio[11131]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:20 marius volumio[11131]: at doSend (dgram.js:692:16) Jun 27 03:49:20 marius volumio[11131]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:20 marius volumio[11131]: at afterDns (dgram.js:638:5) Jun 27 03:49:20 marius volumio[11131]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:20 marius volumio[11131]: errno: -101, Jun 27 03:49:20 marius volumio[11131]: code: 'ENETUNREACH', Jun 27 03:49:20 marius volumio[11131]: syscall: 'send', Jun 27 03:49:20 marius volumio[11131]: address: '255.255.255.255', Jun 27 03:49:20 marius volumio[11131]: port: 3483 Jun 27 03:49:20 marius volumio[11131]: } Jun 27 03:49:20 marius volumio[11131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:20 marius systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 03:49:20 marius sudo[11289]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:20 marius sudo[11281]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:20 marius sudo[11294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:20 marius sudo[11294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:20 marius sudo[11294]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:21 marius mpd[11231]: Jun 27 03:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:21 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:21 marius sudo[11212]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:21 marius sudo[11222]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:21 marius sudo[11310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:21 marius sudo[11310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:21 marius sudo[11310]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:21 marius volumio-remote-updater[656]: [2024-06-27 03:49:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:21 marius volumio-remote-updater[656]: [2024-06-27 03:49:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:21 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:21 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:21 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:21 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:21 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:21 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 55. Jun 27 03:49:21 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:21 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:21 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:21 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:23 marius volumio[11324]: info: ------------------------------------------- Jun 27 03:49:23 marius volumio[11324]: info: ----- Volumio3 ---- Jun 27 03:49:23 marius volumio[11324]: info: ------------------------------------------- Jun 27 03:49:23 marius volumio[11324]: info: ----- System startup ---- Jun 27 03:49:23 marius volumio[11324]: info: ------------------------------------------- Jun 27 03:49:24 marius volumio[11324]: info: MYVOLUMIO Environment detected Jun 27 03:49:24 marius volumio[11324]: info: Plugin folders cleanup Jun 27 03:49:24 marius volumio[11324]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:24 marius volumio[11324]: info: Scanning category audio_interface Jun 27 03:49:24 marius volumio[11324]: info: Scanning category miscellanea Jun 27 03:49:24 marius volumio[11324]: info: Scanning category music_service Jun 27 03:49:24 marius volumio[11324]: info: Scanning category plugins.json Jun 27 03:49:24 marius volumio[11324]: info: Scanning category system_controller Jun 27 03:49:24 marius volumio[11324]: info: Scanning category user_interface Jun 27 03:49:24 marius volumio[11324]: info: Scanning into folder /data/plugins/ Jun 27 03:49:24 marius volumio[11324]: info: Scanning category music_service Jun 27 03:49:24 marius volumio[11324]: info: Plugin folders cleanup completed Jun 27 03:49:24 marius volumio[11324]: info: ------------------------------------------- Jun 27 03:49:24 marius volumio[11324]: info: ----- Core plugins startup ---- Jun 27 03:49:24 marius volumio[11324]: info: ------------------------------------------- Jun 27 03:49:24 marius volumio[11324]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:24 marius volumio[11324]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:24 marius volumio[11324]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:24 marius volumio[11324]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:24 marius volumio[11324]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:24 marius volumio[11324]: info: Loading plugin "system"... Jun 27 03:49:24 marius volumio[11324]: info: Loading plugin "appearance"... Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "network"... Jun 27 03:49:25 marius volumio[11324]: info: Refreshing Cached IP Addresses Jun 27 03:49:25 marius sudo[11350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:25 marius sudo[11350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:25 marius sudo[11350]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:25 marius sudo[11352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "services"... Jun 27 03:49:25 marius sudo[11352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "alsa_controller"... Jun 27 03:49:25 marius sudo[11352]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:25 marius sudo[11355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:25 marius sudo[11355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:25 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "wizard"... Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "networkfs"... Jun 27 03:49:25 marius volumio[11324]: info: Starting Udev Watcher for removable devices Jun 27 03:49:25 marius volumio[11324]: info: Ignoring mount for partition: boot Jun 27 03:49:25 marius volumio[11324]: info: Ignoring mount for partition: volumio Jun 27 03:49:25 marius volumio[11324]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:25 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "upnp"... Jun 27 03:49:25 marius volumio[11324]: info: [1719452965863] Starting Upmpd Daemon Jun 27 03:49:25 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "my_music"... Jun 27 03:49:25 marius volumio[11324]: info: Loading plugin "mpd"... Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "upnp_browser"... Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "alarm-clock"... Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:26 marius volumio[11324]: info: Starting Shairport Sync Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "last_100"... Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "webradio"... Jun 27 03:49:26 marius volumio-remote-updater[656]: [2024-06-27 03:49:26] [connect] Successful connection Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** For more information see Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:26 marius node[11324]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:26 marius volumio[11324]: *** WARNING *** For more information see Jun 27 03:49:26 marius node[11324]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:26 marius node[11324]: *** WARNING *** For more information see Jun 27 03:49:26 marius node[11324]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:26 marius node[11324]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:26 marius node[11324]: *** WARNING *** For more information see Jun 27 03:49:26 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="CabDentaireSTURZ" Jun 27 03:49:26 marius wpa_supplicant[902]: wlan0: Failed to initiate sched scan Jun 27 03:49:26 marius volumio[11324]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:26 marius volumio[11324]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:26 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:26 marius volumio[11324]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:26 marius sudo[11355]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:27 marius volumio[11324]: info: Loading plugin "outputs"... Jun 27 03:49:27 marius volumio[11324]: info: Loading plugin "albumart"... Jun 27 03:49:27 marius volumio[11324]: info: Plugin example_plugin is not enabled Jun 27 03:49:27 marius volumio[11324]: info: Loading plugin "inputs"... Jun 27 03:49:27 marius volumio[11324]: info: Loading plugin "updater_comm"... Jun 27 03:49:28 marius volumio[11324]: info: Plugin mpdemulation is not enabled Jun 27 03:49:28 marius volumio[11324]: info: Loading plugin "rest_api"... Jun 27 03:49:28 marius volumio[11324]: info: Loading plugin "websocket"... Jun 27 03:49:28 marius volumio[11324]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:28 marius volumio[11324]: info: Loading i18n strings for locale fr Jun 27 03:49:28 marius volumio[11324]: Updating browse sources language Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:28 marius volumio[11324]: Forking 3 albumart workers Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:28 marius volumio[11324]: Express server listening on port 3000 Jun 27 03:49:28 marius volumio[11324]: [Metrics] WebUI: 5s 42.32ms Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:28 marius volumio-remote-updater[656]: [2024-06-27 03:49:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452966 101 Jun 27 03:49:28 marius volumio[11324]: 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 Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: Reloading queue from file Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::pushState Jun 27 03:49:28 marius volumio[11324]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::setRandom null Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::pushState Jun 27 03:49:28 marius volumio[11324]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:28 marius volumio[11324]: info: Setting Device type: Raspberry PI Jun 27 03:49:28 marius volumio[11324]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::pushState Jun 27 03:49:28 marius volumio[11324]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:28 marius volumio[11324]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:28 marius volumio[11324]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:28 marius volumio[11324]: info: Completed loading Core Plugins Jun 27 03:49:28 marius volumio[11324]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:28 marius volumio[11324]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:28 marius volumio[11324]: info: Output device has changed, restarting MPD Jun 27 03:49:28 marius volumio[11324]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:28 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:28 marius sudo[11406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:28 marius sudo[11406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:28 marius sudo[11404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:28 marius sudo[11404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:28 marius sudo[11404]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:28 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:28 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:28 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:28 marius volumio[11324]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:28 marius volumio[11324]: info: ___________ START PLUGINS ___________ Jun 27 03:49:28 marius volumio[11324]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:28 marius volumio[11324]: info: Creating MPD Configuration file Jun 27 03:49:28 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:28 marius sudo[11413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:28 marius sudo[11413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:29 marius sudo[11413]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:29 marius volumio[11324]: info: [1719452969011] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:29 marius sudo[11416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:29 marius sudo[11416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:29 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:29 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:29 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:29 marius volumio[11324]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:29 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:29 marius volumio[11324]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:29 marius volumio[11324]: info: [1719452969243] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:29 marius volumio[11324]: info: [1719452969247] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:29 marius volumio[11324]: info: Initializing BBC Radios Jun 27 03:49:29 marius sudo[11421]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:29 marius sudo[11421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:29 marius sudo[11421]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:29 marius volumio[11324]: Starting albumart workers Jun 27 03:49:29 marius volumio[11324]: info: Volumio Calling Home Jun 27 03:49:29 marius volumio[11324]: Starting albumart workers Jun 27 03:49:29 marius volumio[11324]: info: [squeezelite_mc] Proxy server started on port 36533 Jun 27 03:49:29 marius volumio[11324]: Starting albumart workers Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:29 marius volumio[11324]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:29 marius volumio[11324]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:29 marius volumio[11324]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:29 marius volumio[11324]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:29 marius volumio[11324]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:29 marius volumio[11324]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:29 marius volumio[11324]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:29 marius volumio[11324]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:29 marius volumio[11324]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:29 marius volumio[11324]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:29 marius volumio[11324]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:29 marius volumio[11324]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:29 marius volumio[11324]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:29 marius volumio[11324]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:29 marius volumio[11324]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:29 marius volumio[11324]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:29 marius volumio[11324]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:29 marius volumio[11324]: info: MPD Permissions set Jun 27 03:49:29 marius volumio[11324]: info: MPD Permissions set Jun 27 03:49:29 marius volumio[11324]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:29 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:30 marius volumio[11324]: info: CoreStateMachine::pushState Jun 27 03:49:30 marius volumio[11324]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:30 marius volumio[11324]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:30 marius volumio[11324]: info: CoreStateMachine::pushState Jun 27 03:49:30 marius volumio[11324]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:30 marius volumio[11324]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:30 marius volumio[11324]: info: [squeezelite_mc] Player finder started Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:30 marius volumio[11324]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:30 marius volumio[11324]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:30 marius volumio[11324]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:30 marius volumio[11324]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:30 marius volumio[11324]: at doSend (dgram.js:692:16) Jun 27 03:49:30 marius volumio[11324]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:30 marius volumio[11324]: at afterDns (dgram.js:638:5) Jun 27 03:49:30 marius volumio[11324]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:30 marius volumio[11324]: errno: -101, Jun 27 03:49:30 marius volumio[11324]: code: 'ENETUNREACH', Jun 27 03:49:30 marius volumio[11324]: syscall: 'send', Jun 27 03:49:30 marius volumio[11324]: address: '255.255.255.255', Jun 27 03:49:30 marius volumio[11324]: port: 3483 Jun 27 03:49:30 marius volumio[11324]: } Jun 27 03:49:30 marius volumio[11324]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:30 marius sudo[11453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:30 marius sudo[11453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:30 marius sudo[11453]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:31 marius sudo[11488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:31 marius sudo[11488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:31 marius sudo[11488]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:31 marius mpd[11428]: Jun 27 03:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:31 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:31 marius sudo[11406]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:31 marius sudo[11416]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:31 marius volumio-remote-updater[656]: [2024-06-27 03:49:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:31 marius volumio-remote-updater[656]: [2024-06-27 03:49:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:31 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:31 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:31 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:31 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:31 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:31 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 56. Jun 27 03:49:31 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:31 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:31 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:31 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:32 marius wpa_supplicant[902]: wlan0: Failed to initiate sched scan Jun 27 03:49:33 marius volumio[11504]: info: ------------------------------------------- Jun 27 03:49:33 marius volumio[11504]: info: ----- Volumio3 ---- Jun 27 03:49:33 marius volumio[11504]: info: ------------------------------------------- Jun 27 03:49:33 marius volumio[11504]: info: ----- System startup ---- Jun 27 03:49:33 marius volumio[11504]: info: ------------------------------------------- Jun 27 03:49:34 marius volumio[11504]: info: MYVOLUMIO Environment detected Jun 27 03:49:34 marius volumio[11504]: info: Plugin folders cleanup Jun 27 03:49:34 marius volumio[11504]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:34 marius volumio[11504]: info: Scanning category audio_interface Jun 27 03:49:34 marius volumio[11504]: info: Scanning category miscellanea Jun 27 03:49:34 marius volumio[11504]: info: Scanning category music_service Jun 27 03:49:34 marius volumio[11504]: info: Scanning category plugins.json Jun 27 03:49:34 marius volumio[11504]: info: Scanning category system_controller Jun 27 03:49:34 marius volumio[11504]: info: Scanning category user_interface Jun 27 03:49:34 marius volumio[11504]: info: Scanning into folder /data/plugins/ Jun 27 03:49:34 marius volumio[11504]: info: Scanning category music_service Jun 27 03:49:34 marius volumio[11504]: info: Plugin folders cleanup completed Jun 27 03:49:34 marius volumio[11504]: info: ------------------------------------------- Jun 27 03:49:34 marius volumio[11504]: info: ----- Core plugins startup ---- Jun 27 03:49:34 marius volumio[11504]: info: ------------------------------------------- Jun 27 03:49:34 marius volumio[11504]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:34 marius volumio[11504]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:34 marius volumio[11504]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:34 marius volumio[11504]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:34 marius volumio[11504]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:34 marius volumio[11504]: info: Loading plugin "system"... Jun 27 03:49:34 marius volumio[11504]: info: Loading plugin "appearance"... Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "network"... Jun 27 03:49:35 marius volumio[11504]: info: Refreshing Cached IP Addresses Jun 27 03:49:35 marius sudo[11530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:35 marius sudo[11530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:35 marius sudo[11530]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:35 marius sudo[11532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "services"... Jun 27 03:49:35 marius sudo[11532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "alsa_controller"... Jun 27 03:49:35 marius sudo[11532]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:35 marius sudo[11535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:35 marius sudo[11535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:35 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "wizard"... Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "networkfs"... Jun 27 03:49:35 marius volumio[11504]: info: Starting Udev Watcher for removable devices Jun 27 03:49:35 marius volumio[11504]: info: Ignoring mount for partition: boot Jun 27 03:49:35 marius volumio[11504]: info: Ignoring mount for partition: volumio Jun 27 03:49:35 marius volumio[11504]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:35 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "upnp"... Jun 27 03:49:35 marius volumio[11504]: info: [1719452975673] Starting Upmpd Daemon Jun 27 03:49:35 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "my_music"... Jun 27 03:49:35 marius volumio[11504]: info: Loading plugin "mpd"... Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "upnp_browser"... Jun 27 03:49:36 marius sudo[11535]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "alarm-clock"... Jun 27 03:49:36 marius volumio-remote-updater[656]: [2024-06-27 03:49:36] [connect] Successful connection Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:36 marius volumio[11504]: info: Starting Shairport Sync Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "last_100"... Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "webradio"... Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** For more information see Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:36 marius node[11504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:36 marius volumio[11504]: *** WARNING *** For more information see Jun 27 03:49:36 marius node[11504]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:36 marius node[11504]: *** WARNING *** For more information see Jun 27 03:49:36 marius node[11504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:36 marius node[11504]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:36 marius node[11504]: *** WARNING *** For more information see Jun 27 03:49:36 marius volumio[11504]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:36 marius volumio[11504]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:36 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:36 marius volumio[11504]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:37 marius ntpd[794]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "outputs"... Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "albumart"... Jun 27 03:49:37 marius volumio[11504]: info: Plugin example_plugin is not enabled Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "inputs"... Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "updater_comm"... Jun 27 03:49:37 marius volumio[11504]: info: Plugin mpdemulation is not enabled Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "rest_api"... Jun 27 03:49:37 marius volumio[11504]: info: Loading plugin "websocket"... Jun 27 03:49:37 marius volumio[11504]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:37 marius volumio[11504]: info: Loading i18n strings for locale fr Jun 27 03:49:37 marius volumio[11504]: Updating browse sources language Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:37 marius volumio[11504]: Forking 3 albumart workers Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:37 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:38 marius volumio[11504]: Express server listening on port 3000 Jun 27 03:49:38 marius volumio[11504]: [Metrics] WebUI: 5s 6.65ms Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:38 marius wpa_supplicant[902]: wlan0: Failed to initiate sched scan Jun 27 03:49:38 marius volumio-remote-updater[656]: [2024-06-27 03:49:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452976 101 Jun 27 03:49:38 marius volumio[11504]: 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 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:38 marius volumio[11504]: info: Reloading queue from file Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::pushState Jun 27 03:49:38 marius volumio[11504]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::setRandom null Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::pushState Jun 27 03:49:38 marius volumio[11504]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:38 marius volumio[11504]: info: Setting Device type: Raspberry PI Jun 27 03:49:38 marius volumio[11504]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::pushState Jun 27 03:49:38 marius volumio[11504]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:38 marius volumio[11504]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:38 marius volumio[11504]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:38 marius volumio[11504]: info: Completed loading Core Plugins Jun 27 03:49:38 marius volumio[11504]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:38 marius volumio[11504]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:38 marius volumio[11504]: info: Output device has changed, restarting MPD Jun 27 03:49:38 marius volumio[11504]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:38 marius sudo[11584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:38 marius sudo[11584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:38 marius sudo[11584]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:38 marius sudo[11586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:38 marius sudo[11586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:38 marius volumio[11504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:38 marius volumio[11504]: info: ___________ START PLUGINS ___________ Jun 27 03:49:38 marius volumio[11504]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:38 marius volumio[11504]: info: Creating MPD Configuration file Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:38 marius volumio[11504]: info: [1719452978669] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:38 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:38 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:38 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:38 marius sudo[11593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:38 marius sudo[11593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:38 marius sudo[11593]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:38 marius sudo[11594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:38 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:38 marius sudo[11594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:38 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:38 marius volumio[11504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:38 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:38 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:38 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:38 marius volumio[11504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:38 marius volumio[11504]: info: [1719452978893] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:38 marius volumio[11504]: info: [1719452978920] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:38 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:38 marius volumio[11504]: info: Initializing BBC Radios Jun 27 03:49:38 marius sudo[11601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:38 marius sudo[11601]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:38 marius sudo[11601]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:39 marius volumio[11504]: info: Volumio Calling Home Jun 27 03:49:39 marius volumio[11504]: Starting albumart workers Jun 27 03:49:39 marius ntpd[794]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 27 03:49:39 marius volumio[11504]: Starting albumart workers Jun 27 03:49:39 marius volumio[11504]: Starting albumart workers Jun 27 03:49:39 marius volumio[11504]: info: [squeezelite_mc] Proxy server started on port 39679 Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:39 marius volumio[11504]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:39 marius volumio[11504]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:39 marius volumio[11504]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:39 marius volumio[11504]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:39 marius volumio[11504]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:39 marius volumio[11504]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:39 marius volumio[11504]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:39 marius volumio[11504]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:39 marius volumio[11504]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:39 marius volumio[11504]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:39 marius volumio[11504]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:39 marius volumio[11504]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:39 marius volumio[11504]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:39 marius volumio[11504]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:39 marius volumio[11504]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:39 marius volumio[11504]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:39 marius volumio[11504]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:39 marius volumio[11504]: info: MPD Permissions set Jun 27 03:49:39 marius volumio[11504]: info: MPD Permissions set Jun 27 03:49:39 marius volumio[11504]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:39 marius volumio[11504]: info: CoreStateMachine::pushState Jun 27 03:49:39 marius volumio[11504]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:39 marius volumio[11504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:39 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:40 marius volumio[11504]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:40 marius volumio[11504]: info: CoreStateMachine::pushState Jun 27 03:49:40 marius volumio[11504]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:40 marius volumio[11504]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:40 marius volumio[11504]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:40 marius volumio[11504]: info: [squeezelite_mc] Player finder started Jun 27 03:49:40 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:40 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:40 marius volumio[11504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:40 marius volumio[11504]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:40 marius volumio[11504]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:40 marius volumio[11504]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:40 marius volumio[11504]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:40 marius volumio[11504]: at doSend (dgram.js:692:16) Jun 27 03:49:40 marius volumio[11504]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:40 marius volumio[11504]: at afterDns (dgram.js:638:5) Jun 27 03:49:40 marius volumio[11504]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:40 marius volumio[11504]: errno: -101, Jun 27 03:49:40 marius volumio[11504]: code: 'ENETUNREACH', Jun 27 03:49:40 marius volumio[11504]: syscall: 'send', Jun 27 03:49:40 marius volumio[11504]: address: '255.255.255.255', Jun 27 03:49:40 marius volumio[11504]: port: 3483 Jun 27 03:49:40 marius volumio[11504]: } Jun 27 03:49:40 marius volumio[11504]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:40 marius sudo[11633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:40 marius sudo[11633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:40 marius sudo[11633]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:40 marius sudo[11668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:40 marius sudo[11668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:41 marius mpd[11608]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:41 marius mpd[11608]: output: No 'audio_output' defined in config file Jun 27 03:49:41 marius mpd[11608]: output: Successfully detected a sndio audio device Jun 27 03:49:41 marius mpd[11608]: zeroconf: No global port, disabling zeroconf Jun 27 03:49:41 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:41 marius sudo[11594]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:41 marius sudo[11586]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:41 marius sudo[11668]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:41 marius ntpd[794]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 27 03:49:41 marius volumio-remote-updater[656]: [2024-06-27 03:49:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:41 marius volumio-remote-updater[656]: [2024-06-27 03:49:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:41 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:41 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:41 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:41 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:41 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:41 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 57. Jun 27 03:49:41 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:41 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:41 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:41 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:42 marius ntpd[794]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 27 03:49:43 marius volumio[11684]: info: ------------------------------------------- Jun 27 03:49:43 marius volumio[11684]: info: ----- Volumio3 ---- Jun 27 03:49:43 marius volumio[11684]: info: ------------------------------------------- Jun 27 03:49:43 marius volumio[11684]: info: ----- System startup ---- Jun 27 03:49:43 marius volumio[11684]: info: ------------------------------------------- Jun 27 03:49:43 marius wpa_supplicant[902]: wlan0: Failed to initiate sched scan Jun 27 03:49:44 marius volumio[11684]: info: MYVOLUMIO Environment detected Jun 27 03:49:44 marius volumio[11684]: info: Plugin folders cleanup Jun 27 03:49:44 marius volumio[11684]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:44 marius volumio[11684]: info: Scanning category audio_interface Jun 27 03:49:44 marius volumio[11684]: info: Scanning category miscellanea Jun 27 03:49:44 marius volumio[11684]: info: Scanning category music_service Jun 27 03:49:44 marius volumio[11684]: info: Scanning category plugins.json Jun 27 03:49:44 marius volumio[11684]: info: Scanning category system_controller Jun 27 03:49:44 marius volumio[11684]: info: Scanning category user_interface Jun 27 03:49:44 marius volumio[11684]: info: Scanning into folder /data/plugins/ Jun 27 03:49:44 marius volumio[11684]: info: Scanning category music_service Jun 27 03:49:44 marius volumio[11684]: info: Plugin folders cleanup completed Jun 27 03:49:44 marius volumio[11684]: info: ------------------------------------------- Jun 27 03:49:44 marius volumio[11684]: info: ----- Core plugins startup ---- Jun 27 03:49:44 marius volumio[11684]: info: ------------------------------------------- Jun 27 03:49:44 marius volumio[11684]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:44 marius volumio[11684]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:44 marius volumio[11684]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:44 marius volumio[11684]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:44 marius volumio[11684]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:44 marius volumio[11684]: info: Loading plugin "system"... Jun 27 03:49:44 marius volumio[11684]: info: Loading plugin "appearance"... Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "network"... Jun 27 03:49:45 marius volumio[11684]: info: Refreshing Cached IP Addresses Jun 27 03:49:45 marius sudo[11710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:45 marius sudo[11710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:45 marius sudo[11710]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:45 marius sudo[11712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:45 marius sudo[11712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "services"... Jun 27 03:49:45 marius sudo[11712]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "alsa_controller"... Jun 27 03:49:45 marius sudo[11715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:45 marius sudo[11715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:45 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "wizard"... Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "networkfs"... Jun 27 03:49:45 marius volumio[11684]: info: Starting Udev Watcher for removable devices Jun 27 03:49:45 marius volumio[11684]: info: Ignoring mount for partition: boot Jun 27 03:49:45 marius volumio[11684]: info: Ignoring mount for partition: volumio Jun 27 03:49:45 marius volumio[11684]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:45 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "upnp"... Jun 27 03:49:45 marius volumio[11684]: info: [1719452985483] Starting Upmpd Daemon Jun 27 03:49:45 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "my_music"... Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "mpd"... Jun 27 03:49:45 marius volumio[11684]: info: Loading plugin "upnp_browser"... Jun 27 03:49:45 marius sudo[11715]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "alarm-clock"... Jun 27 03:49:46 marius volumio-remote-updater[656]: [2024-06-27 03:49:46] [connect] Successful connection Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:46 marius volumio[11684]: info: Starting Shairport Sync Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "last_100"... Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "webradio"... Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** For more information see Jun 27 03:49:46 marius node[11684]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:46 marius volumio[11684]: *** WARNING *** For more information see Jun 27 03:49:46 marius node[11684]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:46 marius node[11684]: *** WARNING *** For more information see Jun 27 03:49:46 marius node[11684]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:46 marius node[11684]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:46 marius node[11684]: *** WARNING *** For more information see Jun 27 03:49:46 marius volumio[11684]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:46 marius volumio[11684]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:46 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:46 marius volumio[11684]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "outputs"... Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "albumart"... Jun 27 03:49:47 marius volumio[11684]: info: Plugin example_plugin is not enabled Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "inputs"... Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "updater_comm"... Jun 27 03:49:47 marius volumio[11684]: info: Plugin mpdemulation is not enabled Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "rest_api"... Jun 27 03:49:47 marius volumio[11684]: info: Loading plugin "websocket"... Jun 27 03:49:47 marius volumio[11684]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:47 marius volumio[11684]: info: Loading i18n strings for locale fr Jun 27 03:49:47 marius volumio[11684]: Updating browse sources language Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:47 marius volumio[11684]: Forking 3 albumart workers Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:47 marius volumio[11684]: Express server listening on port 3000 Jun 27 03:49:47 marius volumio[11684]: [Metrics] WebUI: 5s 124.73ms Jun 27 03:49:47 marius volumio[11684]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:47 marius volumio[11684]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:47 marius volumio[11684]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:48 marius volumio-remote-updater[656]: [2024-06-27 03:49:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452986 101 Jun 27 03:49:48 marius volumio[11684]: 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 Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:48 marius volumio[11684]: info: Reloading queue from file Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::pushState Jun 27 03:49:48 marius volumio[11684]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::setRandom null Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::pushState Jun 27 03:49:48 marius volumio[11684]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:48 marius volumio[11684]: info: Setting Device type: Raspberry PI Jun 27 03:49:48 marius volumio[11684]: info: Completed loading Core Plugins Jun 27 03:49:48 marius volumio[11684]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:48 marius volumio[11684]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::pushState Jun 27 03:49:48 marius volumio[11684]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:48 marius volumio[11684]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:48 marius volumio[11684]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:48 marius volumio[11684]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:48 marius volumio[11684]: info: Output device has changed, restarting MPD Jun 27 03:49:48 marius volumio[11684]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:48 marius sudo[11802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:48 marius sudo[11802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:48 marius sudo[11802]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:48 marius sudo[11804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:48 marius sudo[11804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:48 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:48 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:48 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:48 marius volumio[11684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:48 marius volumio[11684]: info: ___________ START PLUGINS ___________ Jun 27 03:49:48 marius volumio[11684]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:48 marius volumio[11684]: info: Creating MPD Configuration file Jun 27 03:49:48 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:48 marius volumio[11684]: info: [1719452988771] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:48 marius sudo[11811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:48 marius sudo[11811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:48 marius sudo[11811]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:48 marius sudo[11813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:48 marius sudo[11813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:48 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:48 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:48 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:48 marius volumio[11684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:48 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:48 marius volumio[11684]: Starting albumart workers Jun 27 03:49:48 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:49 marius volumio[11684]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:49 marius volumio[11684]: info: [1719452989072] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:49 marius volumio[11684]: info: [1719452989077] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:49 marius volumio[11684]: Starting albumart workers Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:49 marius volumio[11684]: info: Initializing BBC Radios Jun 27 03:49:49 marius sudo[11819]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:49 marius sudo[11819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:49 marius sudo[11819]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:49 marius volumio[11684]: info: Volumio Calling Home Jun 27 03:49:49 marius wpa_supplicant[902]: wlan0: Failed to initiate sched scan Jun 27 03:49:49 marius volumio[11684]: Starting albumart workers Jun 27 03:49:49 marius volumio[11684]: info: [squeezelite_mc] Proxy server started on port 38559 Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:49 marius volumio[11684]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:49:49 marius volumio[11684]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:49:49 marius volumio[11684]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:49:49 marius volumio[11684]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:49:49 marius volumio[11684]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:49:49 marius volumio[11684]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:49:49 marius volumio[11684]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:49:49 marius volumio[11684]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:49:49 marius volumio[11684]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:49:49 marius volumio[11684]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:49:49 marius volumio[11684]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:49:49 marius volumio[11684]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:49:49 marius volumio[11684]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:49:49 marius volumio[11684]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:49:49 marius volumio[11684]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:49:49 marius volumio[11684]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:49:49 marius volumio[11684]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:49:49 marius volumio[11684]: info: MPD Permissions set Jun 27 03:49:49 marius volumio[11684]: info: MPD Permissions set Jun 27 03:49:49 marius volumio[11684]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:49 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:50 marius volumio[11684]: info: CoreStateMachine::pushState Jun 27 03:49:50 marius volumio[11684]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:50 marius volumio[11684]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:50 marius volumio[11684]: info: CoreStateMachine::pushState Jun 27 03:49:50 marius volumio[11684]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:50 marius volumio[11684]: info: [squeezelite_mc] Server discovery started Jun 27 03:49:50 marius volumio[11684]: info: [squeezelite_mc] Player finder started Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:50 marius volumio[11684]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:49:50 marius volumio[11684]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:50 marius volumio[11684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:50 marius volumio[11684]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:49:50 marius volumio[11684]: at doSend (dgram.js:692:16) Jun 27 03:49:50 marius volumio[11684]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:49:50 marius volumio[11684]: at afterDns (dgram.js:638:5) Jun 27 03:49:50 marius volumio[11684]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:49:50 marius volumio[11684]: errno: -101, Jun 27 03:49:50 marius volumio[11684]: code: 'ENETUNREACH', Jun 27 03:49:50 marius volumio[11684]: syscall: 'send', Jun 27 03:49:50 marius volumio[11684]: address: '255.255.255.255', Jun 27 03:49:50 marius volumio[11684]: port: 3483 Jun 27 03:49:50 marius volumio[11684]: } Jun 27 03:49:50 marius volumio[11684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:49:50 marius sudo[11875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:49:50 marius sudo[11875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:50 marius sudo[11875]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:50 marius sudo[11886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:48 Jun 27 03:49:50 marius sudo[11886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:51 marius mpd[11826]: Jun 27 03:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 03:49:51 marius systemd[1]: Started Music Player Daemon. Jun 27 03:49:51 marius sudo[11804]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:51 marius sudo[11813]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:51 marius sudo[11886]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:51 marius volumio-remote-updater[656]: [2024-06-27 03:49:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 27 03:49:51 marius volumio-remote-updater[656]: [2024-06-27 03:49:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 27 03:49:51 marius systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 27 03:49:51 marius systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 27 03:49:51 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:51 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:51 marius systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 27 03:49:51 marius systemd[1]: volumio.service: Scheduled restart job, restart counter is at 58. Jun 27 03:49:51 marius systemd[1]: Started dynamicswap service. Jun 27 03:49:51 marius systemd[1]: Stopped Volumio Backend Module. Jun 27 03:49:51 marius systemd[1]: Started Volumio Backend Module. Jun 27 03:49:51 marius systemd[1]: dynamicswap.service: Succeeded. Jun 27 03:49:53 marius volumio[11902]: info: ------------------------------------------- Jun 27 03:49:53 marius volumio[11902]: info: ----- Volumio3 ---- Jun 27 03:49:53 marius volumio[11902]: info: ------------------------------------------- Jun 27 03:49:53 marius volumio[11902]: info: ----- System startup ---- Jun 27 03:49:53 marius volumio[11902]: info: ------------------------------------------- Jun 27 03:49:54 marius volumio[11902]: info: MYVOLUMIO Environment detected Jun 27 03:49:54 marius volumio[11902]: info: Plugin folders cleanup Jun 27 03:49:54 marius volumio[11902]: info: Scanning into folder /volumio/app/plugins/ Jun 27 03:49:54 marius volumio[11902]: info: Scanning category audio_interface Jun 27 03:49:54 marius volumio[11902]: info: Scanning category miscellanea Jun 27 03:49:54 marius volumio[11902]: info: Scanning category music_service Jun 27 03:49:54 marius volumio[11902]: info: Scanning category plugins.json Jun 27 03:49:54 marius volumio[11902]: info: Scanning category system_controller Jun 27 03:49:54 marius volumio[11902]: info: Scanning category user_interface Jun 27 03:49:54 marius volumio[11902]: info: Scanning into folder /data/plugins/ Jun 27 03:49:54 marius volumio[11902]: info: Scanning category music_service Jun 27 03:49:54 marius volumio[11902]: info: Plugin folders cleanup completed Jun 27 03:49:54 marius volumio[11902]: info: ------------------------------------------- Jun 27 03:49:54 marius volumio[11902]: info: ----- Core plugins startup ---- Jun 27 03:49:54 marius volumio[11902]: info: ------------------------------------------- Jun 27 03:49:54 marius volumio[11902]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 03:49:54 marius volumio[11902]: info: Adding plugin upnp to MyMusic Plugins Jun 27 03:49:54 marius volumio[11902]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 03:49:54 marius volumio[11902]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 03:49:54 marius volumio[11902]: info: Loading plugins from folder /data/plugins/ Jun 27 03:49:54 marius volumio[11902]: info: Loading plugin "system"... Jun 27 03:49:54 marius volumio[11902]: info: Loading plugin "appearance"... Jun 27 03:49:54 marius wpa_supplicant[902]: wlan0: Trying to associate with SSID 'CabDentaireSTURZ' Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "network"... Jun 27 03:49:55 marius volumio[11902]: info: Refreshing Cached IP Addresses Jun 27 03:49:55 marius sudo[11929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 03:49:55 marius sudo[11929]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:55 marius sudo[11929]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "services"... Jun 27 03:49:55 marius sudo[11931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 03:49:55 marius sudo[11931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "alsa_controller"... Jun 27 03:49:55 marius sudo[11931]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:55 marius sudo[11934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 03:49:55 marius sudo[11934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:55 marius kernel: ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (3) Jun 27 03:49:55 marius sudo[11934]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:55 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "wizard"... Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "networkfs"... Jun 27 03:49:55 marius volumio[11902]: info: Starting Udev Watcher for removable devices Jun 27 03:49:55 marius volumio[11902]: info: Ignoring mount for partition: boot Jun 27 03:49:55 marius volumio[11902]: info: Ignoring mount for partition: volumio Jun 27 03:49:55 marius volumio[11902]: info: Ignoring mount for partition: volumio_data Jun 27 03:49:55 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "volumio_command_line_client"... Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "upnp"... Jun 27 03:49:55 marius volumio[11902]: info: [1719452995469] Starting Upmpd Daemon Jun 27 03:49:55 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "my_music"... Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "mpd"... Jun 27 03:49:55 marius dhcpcd[914]: wlan0: carrier acquired Jun 27 03:49:55 marius wpa_supplicant[902]: wlan0: Associated with 80:2a:a8:c7:15:ac Jun 27 03:49:55 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:2a:a8:c7:15:ac completed [id=0 id_str=] Jun 27 03:49:55 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 27 03:49:55 marius wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Jun 27 03:49:55 marius dhcpcd[914]: wlan0: IAID eb:09:44:11 Jun 27 03:49:55 marius dhcpcd[914]: wlan0: carrier lost Jun 27 03:49:55 marius dhcpcd[914]: wlan0: carrier acquired Jun 27 03:49:55 marius dhcpcd[914]: wlan0: IAID eb:09:44:11 Jun 27 03:49:55 marius dhcpcd[914]: wlan0: soliciting an IPv6 router Jun 27 03:49:55 marius volumio[11902]: info: Loading plugin "upnp_browser"... Jun 27 03:49:55 marius dhcpcd[914]: wlan0: soliciting a DHCP lease Jun 27 03:49:55 marius dhcpcd[914]: wlan0: offered 192.168.1.173 from 192.168.1.1 Jun 27 03:49:56 marius dhcpcd[914]: wlan0: probing address 192.168.1.173/24 Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "alarm-clock"... Jun 27 03:49:56 marius volumio-remote-updater[656]: [2024-06-27 03:49:56] [connect] Successful connection Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "airplay_emulation"... Jun 27 03:49:56 marius volumio[11902]: info: Starting Shairport Sync Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "last_100"... Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "webradio"... Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "i2s_dacs"... Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "volumiodiscovery"... Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** For more information see Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:56 marius node[11902]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:56 marius volumio[11902]: *** WARNING *** For more information see Jun 27 03:49:56 marius node[11902]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:56 marius node[11902]: *** WARNING *** For more information see Jun 27 03:49:56 marius node[11902]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 03:49:56 marius node[11902]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 03:49:56 marius node[11902]: *** WARNING *** For more information see Jun 27 03:49:56 marius volumio[11902]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 03:49:56 marius volumio[11902]: info: Discovery: Started advertising with name: Marius Jun 27 03:49:56 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 03:49:56 marius volumio[11902]: info: Loading plugin "squeezelite_mc"... Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "outputs"... Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "albumart"... Jun 27 03:49:57 marius volumio[11902]: info: Plugin example_plugin is not enabled Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "inputs"... Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "updater_comm"... Jun 27 03:49:57 marius volumio[11902]: info: Plugin mpdemulation is not enabled Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "rest_api"... Jun 27 03:49:57 marius volumio[11902]: info: Loading plugin "websocket"... Jun 27 03:49:57 marius volumio[11902]: info: Starting Socket.io Server version 2.3.0 Jun 27 03:49:57 marius volumio[11902]: info: Loading i18n strings for locale fr Jun 27 03:49:57 marius volumio[11902]: Updating browse sources language Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:57 marius volumio[11902]: Forking 3 albumart workers Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::initPlayerControls Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:57 marius volumio[11902]: Express server listening on port 3000 Jun 27 03:49:57 marius volumio[11902]: [Metrics] WebUI: 5s 64.61ms Jun 27 03:49:57 marius volumio[11902]: info: CoreStateMachine::resetVolumioState Jun 27 03:49:57 marius volumio[11902]: info: CoreStateMachine::getcurrentVolume Jun 27 03:49:57 marius volumio[11902]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:58 marius volumio[11902]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 27 03:49:58 marius volumio[11902]: wlan0 Interface doesn't support scanning : Resource temporarily unavailable Jun 27 03:49:58 marius volumio[11902]: info: Cannot use regular scanning, forcing with ap-force Jun 27 03:49:58 marius sudo[11988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 27 03:49:58 marius sudo[11988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:58 marius volumio[11902]: Starting albumart workers Jun 27 03:49:58 marius volumio[11902]: Starting albumart workers Jun 27 03:49:58 marius volumio[11902]: Starting albumart workers Jun 27 03:49:58 marius sudo[11988]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:58 marius volumio-remote-updater[656]: [2024-06-27 03:49:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719452996 101 Jun 27 03:49:59 marius volumio[11902]: 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 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: Reloading queue from file Jun 27 03:49:59 marius volumio[11902]: info: Setting Device type: Raspberry PI Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::setRepeat null single undefined Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::pushState Jun 27 03:49:59 marius volumio[11902]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::setRandom null Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::pushState Jun 27 03:49:59 marius volumio[11902]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:59 marius volumio[11902]: info: Completed loading Core Plugins Jun 27 03:49:59 marius volumio[11902]: info: Preparing to generate the ALSA configuration file Jun 27 03:49:59 marius volumio[11902]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::pushState Jun 27 03:49:59 marius volumio[11902]: info: CorePlayQueue::getTrack 0 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioPushState Jun 27 03:49:59 marius volumio[11902]: info: CoreStateMachine::updateTrackBlock Jun 27 03:49:59 marius volumio[11902]: info: CorePlayQueue::getTrackBlock Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:49:59 marius volumio[11902]: info: Asound.conf file unchanged, so no further update is needed Jun 27 03:49:59 marius volumio[11902]: info: Output device has changed, restarting MPD Jun 27 03:49:59 marius volumio[11902]: info: Output device has changed, restarting Shairport Sync Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:59 marius sudo[12006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:59 marius sudo[12006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:59 marius sudo[12006]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:59 marius sudo[12016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:59 marius sudo[12016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:59 marius systemd[1]: Stopping Music Player Daemon... Jun 27 03:49:59 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:59 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:59 marius volumio[11902]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:59 marius volumio[11902]: info: ___________ START PLUGINS ___________ Jun 27 03:49:59 marius volumio[11902]: info: ControllerMpd::onStart: Initializing MPD Jun 27 03:49:59 marius volumio[11902]: info: Creating MPD Configuration file Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 03:49:59 marius sudo[12031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 03:49:59 marius sudo[12031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:59 marius volumio[11902]: info: [1719452999608] CoreMusicLibrary::Adding element Serveurs Média Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:59 marius sudo[12031]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:59 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:59 marius sudo[12033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 03:49:59 marius sudo[12033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:59 marius systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 27 03:49:59 marius systemd[1]: mpd.service: Succeeded. Jun 27 03:49:59 marius systemd[1]: Stopped Music Player Daemon. Jun 27 03:49:59 marius systemd[1]: Starting Music Player Daemon... Jun 27 03:49:59 marius volumio[11902]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:59 marius volumio[11902]: info: [1719452999741] CoreMusicLibrary::Adding element Last_100 Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 03:49:59 marius volumio[11902]: info: [1719452999744] CoreMusicLibrary::Adding element Webradio Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:59 marius volumio[11902]: info: Initializing BBC Radios Jun 27 03:49:59 marius sudo[12039]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 03:49:59 marius sudo[12039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:49:59 marius sudo[12039]: pam_unix(sudo:session): session closed for user root Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 03:49:59 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:49:59 marius volumio[11902]: info: [squeezelite_mc] Starting proxy server... Jun 27 03:49:59 marius volumio[11902]: info: Volumio Calling Home Jun 27 03:49:59 marius volumio[11902]: info: [squeezelite_mc] Proxy server started on port 40167 Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 03:50:00 marius volumio[11902]: Unhandled rejection Error: No sockets available, cannot start. Jun 27 03:50:00 marius volumio[11902]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jun 27 03:50:00 marius volumio[11902]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jun 27 03:50:00 marius volumio[11902]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jun 27 03:50:00 marius volumio[11902]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jun 27 03:50:00 marius volumio[11902]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jun 27 03:50:00 marius volumio[11902]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jun 27 03:50:00 marius volumio[11902]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jun 27 03:50:00 marius volumio[11902]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jun 27 03:50:00 marius volumio[11902]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jun 27 03:50:00 marius volumio[11902]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jun 27 03:50:00 marius volumio[11902]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jun 27 03:50:00 marius volumio[11902]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 27 03:50:00 marius volumio[11902]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 27 03:50:00 marius volumio[11902]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jun 27 03:50:00 marius volumio[11902]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jun 27 03:50:00 marius volumio[11902]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 03:50:00 marius volumio[11902]: info: MPD Permissions set Jun 27 03:50:00 marius volumio[11902]: info: MPD Permissions set Jun 27 03:50:00 marius volumio[11902]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 27 03:50:00 marius volumio[11902]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:50:00 marius volumio[11902]: info: CoreStateMachine::pushState Jun 27 03:50:00 marius volumio[11902]: info: CorePlayQueue::getTrack 0 Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::volumioPushState Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: VolumeController:: Volume=20 Mute =false Jun 27 03:50:00 marius volumio[11902]: info: CoreStateMachine::pushState Jun 27 03:50:00 marius volumio[11902]: info: CorePlayQueue::getTrack 0 Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::volumioPushState Jun 27 03:50:00 marius volumio[11902]: info: [squeezelite_mc] Server discovery started Jun 27 03:50:00 marius volumio[11902]: info: [squeezelite_mc] Player finder started Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 03:50:00 marius volumio[11902]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"Hardware","mixer":"Digital"} Jun 27 03:50:00 marius volumio[11902]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:50:00 marius volumio[11902]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:50:00 marius volumio[11902]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 27 03:50:00 marius volumio[11902]: at doSend (dgram.js:692:16) Jun 27 03:50:00 marius volumio[11902]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 27 03:50:00 marius volumio[11902]: at afterDns (dgram.js:638:5) Jun 27 03:50:00 marius volumio[11902]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 27 03:50:00 marius volumio[11902]: errno: -101, Jun 27 03:50:00 marius volumio[11902]: code: 'ENETUNREACH', Jun 27 03:50:00 marius volumio[11902]: syscall: 'send', Jun 27 03:50:00 marius volumio[11902]: address: '255.255.255.255', Jun 27 03:50:00 marius volumio[11902]: port: 3483 Jun 27 03:50:00 marius volumio[11902]: } Jun 27 03:50:00 marius volumio[11902]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 03:50:00 marius sudo[12071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 03:50:00 marius sudo[12071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 03:50:00 marius sudo[12071]: pam_unix(sudo:session): session closed for user root Jun 27 03:50:00 marius dhcpcd[914]: wlan0: leased 192.168.1.173 for 86400 seconds Jun 27 03:50:00 marius avahi-daemon[651]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.173. Jun 27 03:50:00 marius avahi-daemon[651]: New relevant interface wlan0.IPv4 for mDNS. Jun 27 03:50:00 marius dhcpcd[914]: wlan0: adding route to 192.168.1.0/24 Jun 27 03:50:00 marius avahi-daemon[651]: Registering new address record for 192.168.1.173 on wlan0.IPv4. Jun 27 03:50:00 marius dhcpcd[914]: wlan0: adding default route via 192.168.1.1 Jun 27 03:50:01 marius sudo[12104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-06-27 03:49 Jun 27 03:50:01 marius sudo[12104]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"