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