-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-04-30 19:45:08 CEST. --
Apr 30 19:44:00 volumio volumio[3133]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="authenticated as fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:00 volumio volumio[3133]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Apr 30 19:44:00 volumio volumio[3133]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:00 volumio volumio[3133]: info: camilladsp service started and running in background, instance 1
Apr 30 19:44:00 volumio volumio[3133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Apr 30 19:44:00 volumio volumio[3133]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:00 volumio volumio[3133]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Apr 30 19:44:00 volumio volumio[3133]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Apr 30 19:44:00 volumio mpd[3358]: Apr 30 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 30 19:44:00 volumio volumio[3133]: info: FusionDsp loaded
Apr 30 19:44:00 volumio volumio[3133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 19:44:00 volumio systemd[1]: Started Music Player Daemon.
Apr 30 19:44:00 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:00 volumio sudo[3343]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:00 volumio sudo[3400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 30 19:44:00 volumio sudo[3400]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:00 volumio volumio[3133]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 30 19:44:00 volumio volumio[3133]: info: Adding Signal Path Element [object Object]
Apr 30 19:44:00 volumio volumio[3133]: info: Adding fusiondspeq DSP Signal Path Element
Apr 30 19:44:00 volumio volumio[3133]: info: FusionDsp - ---- installed callbackRead
Apr 30 19:44:00 volumio volumio[3133]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:00 volumio volumio[3133]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Apr 30 19:44:00 volumio volumio[3133]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Apr 30 19:44:00 volumio volumio[3133]: at onErrorNT (internal/child_process.js:465:16)
Apr 30 19:44:00 volumio volumio[3133]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 30 19:44:00 volumio volumio[3133]: at runNextTicks (internal/process/task_queues.js:62:3)
Apr 30 19:44:00 volumio volumio[3133]: at listOnTimeout (internal/timers.js:523:9)
Apr 30 19:44:00 volumio volumio[3133]: at processTimers (internal/timers.js:497:7) {
Apr 30 19:44:00 volumio volumio[3133]: errno: -2,
Apr 30 19:44:00 volumio volumio[3133]: code: 'ENOENT',
Apr 30 19:44:00 volumio volumio[3133]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:00 volumio volumio[3133]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:00 volumio volumio[3133]: spawnargs: [
Apr 30 19:44:00 volumio volumio[3133]: '-p',
Apr 30 19:44:00 volumio volumio[3133]: 9876,
Apr 30 19:44:00 volumio volumio[3133]: '-o',
Apr 30 19:44:00 volumio volumio[3133]: '/tmp/camilladsp.log',
Apr 30 19:44:00 volumio volumio[3133]: '-l',
Apr 30 19:44:00 volumio volumio[3133]: 'warn',
Apr 30 19:44:00 volumio volumio[3133]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Apr 30 19:44:00 volumio volumio[3133]: ]
Apr 30 19:44:00 volumio volumio[3133]: }
Apr 30 19:44:00 volumio volumio[3133]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="dealer connection opened"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="received connection id: ZDM2OTYyODctMmNlOS00MzdhLWI3ZDgtOWU1Mjg4MTFhM2M4K2RlYWxlcit0Y3A6Ly8wYWNhNTkwOC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNDhGNjIyOTUzQjcyRDhGQ0Q4QTYxODBFRjU1MEY3MjE0NTVEOURFOUQ5QTJDODQ5QzU1OEQ4OEQyMzE3NzVBMA=="
Apr 30 19:44:00 volumio go-librespot[3372]: time="2025-04-30T19:44:00+02:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 30 19:44:01 volumio sudo[3413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 19:43
Apr 30 19:44:01 volumio sudo[3413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:01 volumio sudo[3413]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:01 volumio volumio-remote-updater[466]: [2025-04-30 19:44:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 30 19:44:01 volumio volumio-remote-updater[466]: [2025-04-30 19:44:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 30 19:44:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 30 19:44:01 volumio sudo[3400]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 30 19:44:01 volumio systemd[1]: Started dynamicswap service.
Apr 30 19:44:01 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 30 19:44:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 30 19:44:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9.
Apr 30 19:44:01 volumio systemd[1]: Started dynamicswap service.
Apr 30 19:44:01 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 30 19:44:01 volumio systemd[1]: Started Volumio Backend Module.
Apr 30 19:44:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 79.
Apr 30 19:44:01 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:01 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:01 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 30 19:44:02 volumio volumio[3427]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 80.
Apr 30 19:44:04 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:04 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:04 volumio volumio[3438]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:05 volumio volumio[3424]: info: -------------------------------------------
Apr 30 19:44:05 volumio volumio[3424]: info: ----- Volumio3 ----
Apr 30 19:44:05 volumio volumio[3424]: info: -------------------------------------------
Apr 30 19:44:05 volumio volumio[3424]: info: ----- System startup ----
Apr 30 19:44:05 volumio volumio[3424]: info: -------------------------------------------
Apr 30 19:44:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 81.
Apr 30 19:44:06 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:06 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:06 volumio volumio-remote-updater[466]: [2025-04-30 19:44:06] [connect] Successful connection
Apr 30 19:44:06 volumio volumio[3424]: info: MYVOLUMIO Environment detected
Apr 30 19:44:06 volumio volumio[3451]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:06 volumio volumio[3424]: info: Plugin folders cleanup
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning into folder /volumio/app/plugins/
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category audio_interface
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category miscellanea
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category music_service
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category plugins.json
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category system_controller
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category user_interface
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning into folder /data/plugins/
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category audio_interface
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category music_service
Apr 30 19:44:06 volumio volumio[3424]: info: Scanning category system_controller
Apr 30 19:44:06 volumio volumio[3424]: info: Plugin folders cleanup completed
Apr 30 19:44:06 volumio volumio[3424]: info: -------------------------------------------
Apr 30 19:44:06 volumio volumio[3424]: info: ----- Core plugins startup ----
Apr 30 19:44:06 volumio volumio[3424]: info: -------------------------------------------
Apr 30 19:44:06 volumio volumio[3424]: info: Loading plugins from folder /volumio/app/plugins/
Apr 30 19:44:06 volumio volumio[3424]: info: Adding plugin upnp to MyMusic Plugins
Apr 30 19:44:06 volumio volumio[3424]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 30 19:44:06 volumio volumio[3424]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 30 19:44:06 volumio volumio[3424]: info: Loading plugins from folder /data/plugins/
Apr 30 19:44:06 volumio volumio[3424]: info: Loading plugin "system"...
Apr 30 19:44:06 volumio volumio[3424]: info: Loading plugin "appearance"...
Apr 30 19:44:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 82.
Apr 30 19:44:08 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:08 volumio volumio[3424]: info: Loading plugin "network"...
Apr 30 19:44:08 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:08 volumio volumio[3424]: info: Refreshing Cached IP Addresses
Apr 30 19:44:08 volumio sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:44:08 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:08 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:08 volumio sudo[3461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:44:08 volumio sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:08 volumio volumio[3424]: info: Loading plugin "services"...
Apr 30 19:44:08 volumio volumio[3424]: info: Loading plugin "alsa_controller"...
Apr 30 19:44:09 volumio volumio[3457]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:09 volumio sudo[3461]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:09 volumio sudo[3470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 30 19:44:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:09 volumio sudo[3470]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:09 volumio sudo[3470]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:09 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "wizard"...
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "networkfs"...
Apr 30 19:44:09 volumio volumio[3424]: info: Starting Udev Watcher for removable devices
Apr 30 19:44:09 volumio volumio[3424]: info: Ignoring mount for partition: boot
Apr 30 19:44:09 volumio volumio[3424]: info: Ignoring mount for partition: volumio
Apr 30 19:44:09 volumio volumio[3424]: info: Ignoring mount for partition: volumio_data
Apr 30 19:44:09 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "volumio_command_line_client"...
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "upnp"...
Apr 30 19:44:09 volumio volumio[3424]: info: [1746035049381] Starting Upmpd Daemon
Apr 30 19:44:09 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "my_music"...
Apr 30 19:44:09 volumio volumio[3424]: info: Loading plugin "mpd"...
Apr 30 19:44:10 volumio volumio[3424]: info: Loading plugin "upnp_browser"...
Apr 30 19:44:10 volumio volumio[3424]: info: Loading plugin "alarm-clock"...
Apr 30 19:44:10 volumio volumio[3424]: info: Loading plugin "airplay_emulation"...
Apr 30 19:44:10 volumio volumio[3424]: info: Starting Shairport Sync
Apr 30 19:44:10 volumio volumio[3424]: info: Loading plugin "last_100"...
Apr 30 19:44:10 volumio volumio[3424]: info: Loading plugin "webradio"...
Apr 30 19:44:11 volumio volumio[3424]: info: Loading plugin "i2s_dacs"...
Apr 30 19:44:11 volumio volumio[3424]: info: I2S DAC not set, start Auto-detection
Apr 30 19:44:11 volumio volumio[3424]: info: Loading plugin "volumiodiscovery"...
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** For more information see
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:11 volumio volumio[3424]: *** WARNING *** For more information see
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** For more information see
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:11 volumio node[3424]: *** WARNING *** For more information see
Apr 30 19:44:11 volumio volumio[3424]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 30 19:44:11 volumio volumio[3424]: info: Discovery: Started advertising with name: Volumio
Apr 30 19:44:11 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 83.
Apr 30 19:44:11 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:11 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:11 volumio volumio[3424]: info: Loading plugin "spop"...
Apr 30 19:44:11 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:11 volumio volumio[3490]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:13 volumio volumio[3424]: info: Loading plugin "autostart"...
Apr 30 19:44:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 84.
Apr 30 19:44:13 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:13 volumio volumio[3424]: info: Applying required configuration parameters for plugin autostart
Apr 30 19:44:13 volumio volumio[3424]: info: AutoStart - onVolumioStart - read config.json
Apr 30 19:44:13 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:13 volumio volumio[3424]: info: Loading plugin "outputs"...
Apr 30 19:44:13 volumio volumio[3424]: info: Loading plugin "albumart"...
Apr 30 19:44:13 volumio volumio[3424]: info: Plugin example_plugin is not enabled
Apr 30 19:44:13 volumio volumio[3424]: info: Loading plugin "inputs"...
Apr 30 19:44:13 volumio volumio[3424]: info: Loading plugin "updater_comm"...
Apr 30 19:44:14 volumio volumio[3531]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:14 volumio volumio[3424]: info: Plugin mpdemulation is not enabled
Apr 30 19:44:14 volumio volumio[3424]: info: Loading plugin "rest_api"...
Apr 30 19:44:14 volumio volumio[3424]: info: Loading plugin "websocket"...
Apr 30 19:44:14 volumio volumio[3424]: info: Starting Socket.io Server version 2.3.0
Apr 30 19:44:14 volumio volumio[3424]: info: Loading plugin "fusiondsp"...
Apr 30 19:44:14 volumio volumio[3424]: Forking 3 albumart workers
Apr 30 19:44:14 volumio volumio[3424]: info: Applying required configuration parameters for plugin fusiondsp
Apr 30 19:44:15 volumio volumio[3424]: info: Loading plugin "rad357pl"...
Apr 30 19:44:15 volumio volumio[3424]: info: Applying required configuration parameters for plugin rad357pl
Apr 30 19:44:15 volumio volumio[3424]: info: [1746035055708] [rad357pl] API delay: 5
Apr 30 19:44:15 volumio volumio[3424]: info: Loading plugin "radio_paradise"...
Apr 30 19:44:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 85.
Apr 30 19:44:16 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:16 volumio volumio[3424]: info: Applying required configuration parameters for plugin radio_paradise
Apr 30 19:44:16 volumio volumio[3424]: info: [1746035056361] [RadioParadise] API delay: 5
Apr 30 19:44:16 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:16 volumio volumio[3424]: info: Loading i18n strings for locale pl
Apr 30 19:44:16 volumio volumio[3424]: Updating browse sources language
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:16 volumio volumio[3563]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::initPlayerControls
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: Starting albumart workers
Apr 30 19:44:16 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:16 volumio volumio[3424]: Express server listening on port 3000
Apr 30 19:44:16 volumio volumio[3424]: [Metrics] WebUI: 12s 714.13ms
Apr 30 19:44:17 volumio volumio[3424]: Starting albumart workers
Apr 30 19:44:17 volumio volumio[3424]: info: CoreStateMachine::resetVolumioState
Apr 30 19:44:17 volumio volumio[3424]: info: CoreStateMachine::getcurrentVolume
Apr 30 19:44:17 volumio volumio[3424]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:44:17 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:17 volumio volumio[3424]: info: Volumio Network Manager: Network status updated: 1
Apr 30 19:44:17 volumio volumio[3424]: Starting albumart workers
Apr 30 19:44:17 volumio volumio[3424]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Apr 30 19:44:17 volumio volumio[3424]: wlan0 Interface doesn't support scanning.
Apr 30 19:44:17 volumio volumio[3424]: info: Cannot use regular scanning, forcing with ap-force
Apr 30 19:44:17 volumio sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Apr 30 19:44:17 volumio sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:17 volumio sudo[3569]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:17 volumio volumio[3424]: command failed: No such device (-19)
Apr 30 19:44:17 volumio volumio[3424]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Apr 30 19:44:17 volumio volumio[3424]: command failed: No such device (-19)
Apr 30 19:44:17 volumio volumio[3424]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1
Apr 30 19:44:17 volumio volumio[3424]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2
Apr 30 19:44:17 volumio volumio[3424]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3
Apr 30 19:44:18 volumio volumio[3424]: info: Reloading queue from file
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::setRepeat null single undefined
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::pushState
Apr 30 19:44:18 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::setRandom null
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::pushState
Apr 30 19:44:18 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:18 volumio volumio[3424]: info: Setting Device type: Raspberry PI
Apr 30 19:44:18 volumio volumio[3424]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::pushState
Apr 30 19:44:18 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:18 volumio volumio[3424]: info: CoreStateMachine::updateTrackBlock
Apr 30 19:44:18 volumio volumio[3424]: info: CorePlayQueue::getTrackBlock
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:44:18 volumio volumio[3424]: info: Completed loading Core Plugins
Apr 30 19:44:18 volumio volumio[3424]: info: Preparing to generate the ALSA configuration file
Apr 30 19:44:18 volumio volumio[3424]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 30 19:44:18 volumio volumio[3424]: info: Reading ALSA contributions from plugins.
Apr 30 19:44:18 volumio volumio[3424]: info: Discovery: adding c22eb07e-309a-4cf9-83fc-cae3e886494f
Apr 30 19:44:18 volumio volumio[3424]: info: Discovery: Found device Volumio
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:18 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:18 volumio volumio[3424]: info: Asound.conf file unchanged, so no further update is needed
Apr 30 19:44:18 volumio volumio[3424]: info: Output device has changed, restarting MPD
Apr 30 19:44:18 volumio volumio[3424]: info: Output device has changed, restarting Shairport Sync
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:18 volumio sudo[3598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 30 19:44:18 volumio sudo[3598]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:18 volumio sudo[3598]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:18 volumio sudo[3600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 30 19:44:18 volumio sudo[3600]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:18 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 86.
Apr 30 19:44:18 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:18 volumio volumio[3424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:18 volumio volumio[3424]: info: ___________ START PLUGINS ___________
Apr 30 19:44:18 volumio volumio[3424]: info: ControllerMpd::onStart: Initializing MPD
Apr 30 19:44:18 volumio volumio[3424]: info: Creating MPD Configuration file
Apr 30 19:44:18 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:18 volumio systemd[1]: Stopping Music Player Daemon...
Apr 30 19:44:18 volumio sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 30 19:44:18 volumio systemd[1]: mpd.service: Succeeded.
Apr 30 19:44:18 volumio sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:18 volumio systemd[1]: Stopped Music Player Daemon.
Apr 30 19:44:18 volumio systemd[1]: Starting Music Player Daemon...
Apr 30 19:44:18 volumio sudo[3616]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:18 volumio volumio[3424]: info: [1746035058954] CoreMusicLibrary::Adding element Serwery Mediów
Apr 30 19:44:18 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:18 volumio sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 30 19:44:18 volumio sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:19 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Apr 30 19:44:19 volumio systemd[1]: mpd.service: Succeeded.
Apr 30 19:44:19 volumio systemd[1]: Stopped Music Player Daemon.
Apr 30 19:44:19 volumio volumio[3614]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:19 volumio systemd[1]: Starting Music Player Daemon...
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:19 volumio volumio[3424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:19 volumio volumio-remote-updater[466]: [2025-04-30 19:44:19] [connect] Successful connection
Apr 30 19:44:19 volumio volumio[3424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:19 volumio volumio[3424]: info: [1746035059263] CoreMusicLibrary::Adding element Last_100
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:19 volumio volumio[3424]: info: [1746035059270] CoreMusicLibrary::Adding element Webradio
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:19 volumio volumio[3424]: info: Initializing BBC Radios
Apr 30 19:44:19 volumio sudo[3622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 30 19:44:19 volumio sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:19 volumio sudo[3622]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:19 volumio volumio[3424]: info: Creating Spotify config file
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:19 volumio volumio[3424]: info: AutoStart - onStart
Apr 30 19:44:19 volumio volumio[3424]: info: Loading i18n strings for locale pl
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:19 volumio volumio[3424]: info: FusionDsp - mixtype--------------------- Hardware
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:19 volumio volumio[3424]: info: [1746035059702] CoreMusicLibrary::Adding element RADIO 357
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:19 volumio volumio[3424]: Cannot find translation for source RADIO 357
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:19 volumio volumio[3424]: info: [1746035059715] CoreMusicLibrary::Adding element Radio Paradise
Apr 30 19:44:19 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:19 volumio volumio[3424]: Cannot find translation for source RADIO 357
Apr 30 19:44:19 volumio volumio[3424]: Cannot find translation for source Radio Paradise
Apr 30 19:44:19 volumio volumio[3424]: info: Volumio Calling Home
Apr 30 19:44:19 volumio volumio[3424]: info: Preparing to generate the ALSA configuration file
Apr 30 19:44:20 volumio sudo[3659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:44:20 volumio sudo[3659]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:20 volumio volumio[3424]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 30 19:44:20 volumio sudo[3661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:44:20 volumio sudo[3661]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:20 volumio volumio[3424]: info: Reading ALSA contributions from plugins.
Apr 30 19:44:20 volumio sudo[3659]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:20 volumio sudo[3661]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:20 volumio volumio[3424]: info: MPD Permissions set
Apr 30 19:44:20 volumio volumio[3424]: info: MPD Permissions set
Apr 30 19:44:20 volumio volumio[3424]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:44:20 volumio volumio[3424]: info: CoreStateMachine::pushState
Apr 30 19:44:20 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:20 volumio volumio-remote-updater[466]: [2025-04-30 19:44:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746035059 101
Apr 30 19:44:20 volumio volumio[3424]: 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: 4
Apr 30 19:44:20 volumio volumio[3424]: info: Volumio called home
Apr 30 19:44:20 volumio volumio[3424]: info: Spotify config file written
Apr 30 19:44:20 volumio sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Apr 30 19:44:20 volumio sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:20 volumio systemd[1]: Stopping go-librespot Daemon...
Apr 30 19:44:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 30 19:44:20 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Apr 30 19:44:20 volumio systemd[1]: Stopped go-librespot Daemon.
Apr 30 19:44:20 volumio systemd[1]: Started go-librespot Daemon.
Apr 30 19:44:20 volumio go-librespot[3669]: Librespot-go daemon starting...
Apr 30 19:44:20 volumio sudo[3667]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:20 volumio go-librespot[3669]: time="2025-04-30T19:44:20+02:00" level=info msg="generated new device id: cdc5b37b3cf82614838b8b3577e1ce0335f82ddd"
Apr 30 19:44:20 volumio go-librespot[3669]: time="2025-04-30T19:44:20+02:00" level=debug msg="stored credentials found for fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:20 volumio volumio[3424]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:20 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:21 volumio go-librespot[3669]: time="2025-04-30T19:44:21+02:00" level=debug msg="obtained new client token: AAAnv/R1NKw1tcSvuF02M5+phfJwpoLLxoG1MC8vrftJRpf/rNOnUqtPnTG+WuMm6I3Iqcojq5MslQjF2RaEOt/6AvKtLtkekO7ohnrM7gFKbyKvx4kT9eSM6II2HXq2Wl6/vl4Gcrfd/lGtso+krKeXLqCEUuEVmXAYsBai+reks/fKlEDi+VtJRXfqKX3KdSliVGFmsq4Wanc9/5gg/zDFZCrXdz8ZkW2JO5AU7u8ikTpYpqf049WcYFDv3Q=="
Apr 30 19:44:21 volumio go-librespot[3669]: time="2025-04-30T19:44:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Apr 30 19:44:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 87.
Apr 30 19:44:21 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:21 volumio volumio[3424]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6
Apr 30 19:44:21 volumio volumio[3424]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 30 19:44:21 volumio volumio[3424]: SPOTIFY: BQDpeNF-RiZZrLkmkoaAkGtDiuQH_6Kqtud4w_RAiYvWaCf4LAjlf_55cxLmfO8pEqPu3ZHOjjxksL7gIZfDPLBLPXMUwVmVvcZw6HgkM8NQVaKYXuE90C037_5_94tEWXKhUziXgYu01NPvwRox-Ote5IEJL6PQqsY0aRVBbpzv2ZtmC4nxurQMczwP-CiArhoyUd_JC3u8_dG4A2BRPvu78lcD0UAh8HhXmAXkeV0CJiK_IsTiEI8BtTH8FLloCHFsbJjph3pxlsfvXJaFwNvsbOPnhUeMzia0pIIVXaOq1-Y
Apr 30 19:44:21 volumio volumio[3424]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 30 19:44:21 volumio volumio[3424]: info: New Spotify access token = BQDpeNF-RiZZrLkmkoaAkGtDiuQH_6Kqtud4w_RAiYvWaCf4LAjlf_55cxLmfO8pEqPu3ZHOjjxksL7gIZfDPLBLPXMUwVmVvcZw6HgkM8NQVaKYXuE90C037_5_94tEWXKhUziXgYu01NPvwRox-Ote5IEJL6PQqsY0aRVBbpzv2ZtmC4nxurQMczwP-CiArhoyUd_JC3u8_dG4A2BRPvu78lcD0UAh8HhXmAXkeV0CJiK_IsTiEI8BtTH8FLloCHFsbJjph3pxlsfvXJaFwNvsbOPnhUeMzia0pIIVXaOq1-Y
Apr 30 19:44:21 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:21 volumio volumio[3424]: info: Spotify credentials grant success - running version from March 24, 2019
Apr 30 19:44:21 volumio volumio[3424]: info: Starting Shairport Sync
Apr 30 19:44:21 volumio go-librespot[3669]: time="2025-04-30T19:44:21+02:00" level=debug msg="completed keyexchange"
Apr 30 19:44:21 volumio volumio[3424]: info: Starting Shairport Sync
Apr 30 19:44:21 volumio volumio[3424]: info: Starting Shairport Sync
Apr 30 19:44:21 volumio sudo[3689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:21 volumio sudo[3689]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:21 volumio volumio[3687]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:21 volumio sudo[3691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:21 volumio sudo[3691]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 19:44:21 volumio sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:21 volumio sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:21 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 30 19:44:21 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 30 19:44:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:21 volumio sudo[3698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 30 19:44:21 volumio sudo[3698]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:21 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 30 19:44:21 volumio volumio[3424]: info: Received Get System Info
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 19:44:21 volumio volumio[3424]: info: Discovery: Getting this device information
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:21 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 19:44:21 volumio volumio[3424]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:21 volumio volumio[3424]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:21 volumio sudo[3689]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:21 volumio sudo[3691]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:21 volumio volumio[3424]: info: Listing playlists
Apr 30 19:44:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 30 19:44:21 volumio sudo[3698]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Apr 30 19:44:21 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 30 19:44:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:21 volumio go-librespot[3669]: time="2025-04-30T19:44:21+02:00" level=debug msg="completed challenge"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="authenticated as fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:22 volumio sudo[3693]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:22 volumio volumio[3424]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:22 volumio volumio[3424]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Apr 30 19:44:22 volumio volumio[3424]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:22 volumio volumio[3424]: info: camilladsp service started and running in background, instance 1
Apr 30 19:44:22 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="authenticated as fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:22 volumio volumio[3424]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:22 volumio volumio[3424]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Apr 30 19:44:22 volumio volumio[3424]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Apr 30 19:44:22 volumio volumio[3424]: info: FusionDsp loaded
Apr 30 19:44:22 volumio volumio[3424]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Apr 30 19:44:22 volumio sudo[3714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 30 19:44:22 volumio sudo[3714]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:22 volumio volumio[3424]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 30 19:44:22 volumio volumio[3424]: info: Adding Signal Path Element [object Object]
Apr 30 19:44:22 volumio volumio[3424]: info: Adding fusiondspeq DSP Signal Path Element
Apr 30 19:44:22 volumio volumio[3424]: info: FusionDsp - ---- installed callbackRead
Apr 30 19:44:22 volumio volumio[3424]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:22 volumio volumio[3424]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Apr 30 19:44:22 volumio volumio[3424]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Apr 30 19:44:22 volumio volumio[3424]: at onErrorNT (internal/child_process.js:465:16)
Apr 30 19:44:22 volumio volumio[3424]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 30 19:44:22 volumio volumio[3424]: at runNextTicks (internal/process/task_queues.js:62:3)
Apr 30 19:44:22 volumio volumio[3424]: at listOnTimeout (internal/timers.js:523:9)
Apr 30 19:44:22 volumio volumio[3424]: at processTimers (internal/timers.js:497:7) {
Apr 30 19:44:22 volumio volumio[3424]: errno: -2,
Apr 30 19:44:22 volumio volumio[3424]: code: 'ENOENT',
Apr 30 19:44:22 volumio volumio[3424]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:22 volumio volumio[3424]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:22 volumio volumio[3424]: spawnargs: [
Apr 30 19:44:22 volumio volumio[3424]: '-p',
Apr 30 19:44:22 volumio volumio[3424]: 9876,
Apr 30 19:44:22 volumio volumio[3424]: '-o',
Apr 30 19:44:22 volumio volumio[3424]: '/tmp/camilladsp.log',
Apr 30 19:44:22 volumio volumio[3424]: '-l',
Apr 30 19:44:22 volumio volumio[3424]: 'warn',
Apr 30 19:44:22 volumio volumio[3424]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Apr 30 19:44:22 volumio volumio[3424]: ]
Apr 30 19:44:22 volumio volumio[3424]: }
Apr 30 19:44:22 volumio volumio[3424]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="dealer connection opened"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="received connection id: NzgxNjZkMTEtZGI5Ni00YjQ0LTgzNmUtN2JiOTE0NDk2MjRmK2RlYWxlcit0Y3A6Ly8wYWNhNTljZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjM0OURBN0QzNjIyNUZGNzVDQjc1NjUwNjY2OUFDMkY3NTEzOENGQTkxODMzRkMyMkM1NkE1QkIzODFDQURBMw=="
Apr 30 19:44:22 volumio mpd[3646]: Apr 30 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 30 19:44:22 volumio systemd[1]: Started Music Player Daemon.
Apr 30 19:44:22 volumio sudo[3620]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:22 volumio sudo[3600]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:22 volumio go-librespot[3669]: time="2025-04-30T19:44:22+02:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 30 19:44:23 volumio sudo[3728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 19:43
Apr 30 19:44:23 volumio sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 88.
Apr 30 19:44:23 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:23 volumio sudo[3728]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:23 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:23 volumio sudo[3714]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 30 19:44:23 volumio volumio-remote-updater[466]: [2025-04-30 19:44:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 30 19:44:23 volumio volumio-remote-updater[466]: [2025-04-30 19:44:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 30 19:44:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 30 19:44:24 volumio volumio[3730]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:24 volumio systemd[1]: Started dynamicswap service.
Apr 30 19:44:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 30 19:44:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10.
Apr 30 19:44:24 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 30 19:44:24 volumio systemd[1]: Started Volumio Backend Module.
Apr 30 19:44:24 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 30 19:44:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 89.
Apr 30 19:44:26 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:26 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:26 volumio volumio[3749]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:27 volumio volumio[3737]: info: -------------------------------------------
Apr 30 19:44:27 volumio volumio[3737]: info: ----- Volumio3 ----
Apr 30 19:44:27 volumio volumio[3737]: info: -------------------------------------------
Apr 30 19:44:27 volumio volumio[3737]: info: ----- System startup ----
Apr 30 19:44:27 volumio volumio[3737]: info: -------------------------------------------
Apr 30 19:44:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 90.
Apr 30 19:44:28 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:28 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:28 volumio volumio[3758]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:28 volumio volumio[3737]: info: MYVOLUMIO Environment detected
Apr 30 19:44:28 volumio volumio-remote-updater[466]: [2025-04-30 19:44:28] [connect] Successful connection
Apr 30 19:44:29 volumio volumio[3737]: info: Plugin folders cleanup
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning into folder /volumio/app/plugins/
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category audio_interface
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category miscellanea
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category music_service
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category plugins.json
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category system_controller
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category user_interface
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning into folder /data/plugins/
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category audio_interface
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category music_service
Apr 30 19:44:29 volumio volumio[3737]: info: Scanning category system_controller
Apr 30 19:44:29 volumio volumio[3737]: info: Plugin folders cleanup completed
Apr 30 19:44:29 volumio volumio[3737]: info: -------------------------------------------
Apr 30 19:44:29 volumio volumio[3737]: info: ----- Core plugins startup ----
Apr 30 19:44:29 volumio volumio[3737]: info: -------------------------------------------
Apr 30 19:44:29 volumio volumio[3737]: info: Loading plugins from folder /volumio/app/plugins/
Apr 30 19:44:29 volumio volumio[3737]: info: Adding plugin upnp to MyMusic Plugins
Apr 30 19:44:29 volumio volumio[3737]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 30 19:44:29 volumio volumio[3737]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 30 19:44:29 volumio volumio[3737]: info: Loading plugins from folder /data/plugins/
Apr 30 19:44:29 volumio volumio[3737]: info: Loading plugin "system"...
Apr 30 19:44:29 volumio volumio[3737]: info: Loading plugin "appearance"...
Apr 30 19:44:31 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 91.
Apr 30 19:44:31 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "network"...
Apr 30 19:44:31 volumio volumio[3737]: info: Refreshing Cached IP Addresses
Apr 30 19:44:31 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:31 volumio sudo[3768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:44:31 volumio sudo[3768]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:31 volumio sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "services"...
Apr 30 19:44:31 volumio sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "alsa_controller"...
Apr 30 19:44:31 volumio sudo[3766]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:31 volumio sudo[3775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 30 19:44:31 volumio sudo[3768]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:31 volumio sudo[3775]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:31 volumio sudo[3775]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:31 volumio volumio[3764]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:31 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "wizard"...
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "networkfs"...
Apr 30 19:44:31 volumio volumio[3737]: info: Starting Udev Watcher for removable devices
Apr 30 19:44:31 volumio volumio[3737]: info: Ignoring mount for partition: boot
Apr 30 19:44:31 volumio volumio[3737]: info: Ignoring mount for partition: volumio
Apr 30 19:44:31 volumio volumio[3737]: info: Ignoring mount for partition: volumio_data
Apr 30 19:44:31 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "volumio_command_line_client"...
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "upnp"...
Apr 30 19:44:31 volumio volumio[3737]: info: [1746035071619] Starting Upmpd Daemon
Apr 30 19:44:31 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "my_music"...
Apr 30 19:44:31 volumio volumio[3737]: info: Loading plugin "mpd"...
Apr 30 19:44:32 volumio volumio[3737]: info: Loading plugin "upnp_browser"...
Apr 30 19:44:32 volumio volumio[3737]: info: Loading plugin "alarm-clock"...
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "airplay_emulation"...
Apr 30 19:44:33 volumio volumio[3737]: info: Starting Shairport Sync
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "last_100"...
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "webradio"...
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "i2s_dacs"...
Apr 30 19:44:33 volumio volumio[3737]: info: I2S DAC not set, start Auto-detection
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "volumiodiscovery"...
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** For more information see
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:33 volumio volumio[3737]: *** WARNING *** For more information see
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** For more information see
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:33 volumio node[3737]: *** WARNING *** For more information see
Apr 30 19:44:33 volumio volumio[3737]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 30 19:44:33 volumio volumio[3737]: info: Discovery: Started advertising with name: Volumio
Apr 30 19:44:33 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:33 volumio volumio[3737]: info: Loading plugin "spop"...
Apr 30 19:44:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 92.
Apr 30 19:44:33 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:33 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:33 volumio volumio[3799]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:35 volumio volumio[3737]: info: Loading plugin "autostart"...
Apr 30 19:44:36 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 93.
Apr 30 19:44:36 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:36 volumio volumio[3737]: info: Applying required configuration parameters for plugin autostart
Apr 30 19:44:36 volumio volumio[3737]: info: AutoStart - onVolumioStart - read config.json
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "outputs"...
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "albumart"...
Apr 30 19:44:36 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:36 volumio volumio[3737]: info: Plugin example_plugin is not enabled
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "inputs"...
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "updater_comm"...
Apr 30 19:44:36 volumio volumio[3803]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:36 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:36 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:36 volumio volumio[3737]: info: Plugin mpdemulation is not enabled
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "rest_api"...
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "websocket"...
Apr 30 19:44:36 volumio volumio[3737]: info: Starting Socket.io Server version 2.3.0
Apr 30 19:44:36 volumio volumio[3737]: info: Loading plugin "fusiondsp"...
Apr 30 19:44:36 volumio volumio[3737]: Forking 3 albumart workers
Apr 30 19:44:37 volumio volumio[3737]: info: Applying required configuration parameters for plugin fusiondsp
Apr 30 19:44:37 volumio volumio[3737]: info: Loading plugin "rad357pl"...
Apr 30 19:44:37 volumio volumio[3737]: info: Applying required configuration parameters for plugin rad357pl
Apr 30 19:44:37 volumio volumio[3737]: info: [1746035077924] [rad357pl] API delay: 5
Apr 30 19:44:37 volumio volumio[3737]: info: Loading plugin "radio_paradise"...
Apr 30 19:44:38 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 94.
Apr 30 19:44:38 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:38 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:38 volumio volumio[3737]: info: Applying required configuration parameters for plugin radio_paradise
Apr 30 19:44:38 volumio volumio[3737]: info: [1746035078660] [RadioParadise] API delay: 5
Apr 30 19:44:38 volumio volumio[3737]: info: Loading i18n strings for locale pl
Apr 30 19:44:38 volumio volumio[3737]: Updating browse sources language
Apr 30 19:44:38 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:38 volumio volumio[3832]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:38 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:38 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:39 volumio volumio[3737]: Starting albumart workers
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::initPlayerControls
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: Express server listening on port 3000
Apr 30 19:44:39 volumio volumio[3737]: [Metrics] WebUI: 12s 695.12ms
Apr 30 19:44:39 volumio volumio[3737]: Starting albumart workers
Apr 30 19:44:39 volumio volumio[3737]: info: CoreStateMachine::resetVolumioState
Apr 30 19:44:39 volumio volumio[3737]: info: CoreStateMachine::getcurrentVolume
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:44:39 volumio volumio[3737]: Starting albumart workers
Apr 30 19:44:39 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:39 volumio volumio[3737]: info: Volumio Network Manager: Network status updated: 1
Apr 30 19:44:39 volumio volumio[3737]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Apr 30 19:44:39 volumio volumio[3737]: wlan0 Interface doesn't support scanning.
Apr 30 19:44:39 volumio volumio[3737]: info: Cannot use regular scanning, forcing with ap-force
Apr 30 19:44:39 volumio sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Apr 30 19:44:39 volumio sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:39 volumio sudo[3838]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:39 volumio volumio[3737]: command failed: No such device (-19)
Apr 30 19:44:39 volumio volumio[3737]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Apr 30 19:44:39 volumio volumio[3737]: command failed: No such device (-19)
Apr 30 19:44:39 volumio volumio[3737]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1
Apr 30 19:44:40 volumio volumio[3737]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2
Apr 30 19:44:40 volumio volumio[3737]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3
Apr 30 19:44:40 volumio volumio[3737]: info: Reloading queue from file
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::setRepeat null single undefined
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::pushState
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::setRandom null
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::pushState
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:40 volumio volumio[3737]: info: Setting Device type: Raspberry PI
Apr 30 19:44:40 volumio volumio[3737]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::pushState
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::updateTrackBlock
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrackBlock
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:44:40 volumio volumio[3737]: info: Completed loading Core Plugins
Apr 30 19:44:40 volumio volumio[3737]: info: Preparing to generate the ALSA configuration file
Apr 30 19:44:40 volumio volumio[3737]: info: Discovery: adding c22eb07e-309a-4cf9-83fc-cae3e886494f
Apr 30 19:44:40 volumio volumio[3737]: info: Discovery: Found device Volumio
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:40 volumio volumio[3737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 30 19:44:40 volumio volumio[3737]: info: Reading ALSA contributions from plugins.
Apr 30 19:44:40 volumio volumio[3737]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:44:40 volumio volumio[3737]: info: CoreStateMachine::pushState
Apr 30 19:44:40 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::volumioPushState
Apr 30 19:44:40 volumio volumio[3737]: info: Asound.conf file unchanged, so no further update is needed
Apr 30 19:44:40 volumio volumio[3737]: info: Output device has changed, restarting MPD
Apr 30 19:44:40 volumio volumio[3737]: info: Output device has changed, restarting Shairport Sync
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:40 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:40 volumio sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 30 19:44:40 volumio sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:40 volumio sudo[3875]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:40 volumio sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 30 19:44:40 volumio sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:40 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 95.
Apr 30 19:44:40 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:40 volumio volumio[3737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:40 volumio volumio[3737]: info: ___________ START PLUGINS ___________
Apr 30 19:44:40 volumio volumio[3737]: info: ControllerMpd::onStart: Initializing MPD
Apr 30 19:44:40 volumio volumio[3737]: info: Creating MPD Configuration file
Apr 30 19:44:41 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:41 volumio systemd[1]: Stopping Music Player Daemon...
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:41 volumio volumio[3737]: info: [1746035081048] CoreMusicLibrary::Adding element Serwery Mediów
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:41 volumio sudo[3885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 30 19:44:41 volumio sudo[3885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:41 volumio sudo[3885]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:41 volumio sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 30 19:44:41 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:41 volumio systemd[1]: mpd.service: Succeeded.
Apr 30 19:44:41 volumio systemd[1]: Stopped Music Player Daemon.
Apr 30 19:44:41 volumio volumio[3883]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:41 volumio systemd[1]: Starting Music Player Daemon...
Apr 30 19:44:41 volumio volumio[3737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:41 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Apr 30 19:44:41 volumio systemd[1]: mpd.service: Succeeded.
Apr 30 19:44:41 volumio systemd[1]: Stopped Music Player Daemon.
Apr 30 19:44:41 volumio systemd[1]: Starting Music Player Daemon...
Apr 30 19:44:41 volumio volumio[3737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:41 volumio volumio[3737]: info: [1746035081356] CoreMusicLibrary::Adding element Last_100
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:41 volumio volumio[3737]: info: [1746035081368] CoreMusicLibrary::Adding element Webradio
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:41 volumio volumio[3737]: info: Initializing BBC Radios
Apr 30 19:44:41 volumio volumio-remote-updater[466]: [2025-04-30 19:44:41] [connect] Successful connection
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:41 volumio volumio[3737]: info: Creating Spotify config file
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:41 volumio sudo[3897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 30 19:44:41 volumio sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:41 volumio sudo[3897]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:41 volumio volumio[3737]: info: AutoStart - onStart
Apr 30 19:44:41 volumio volumio[3737]: info: Loading i18n strings for locale pl
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:41 volumio volumio[3737]: info: FusionDsp - mixtype--------------------- Hardware
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:41 volumio volumio[3737]: info: [1746035081808] CoreMusicLibrary::Adding element RADIO 357
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:41 volumio volumio[3737]: Cannot find translation for source RADIO 357
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 30 19:44:41 volumio volumio[3737]: info: [1746035081820] CoreMusicLibrary::Adding element Radio Paradise
Apr 30 19:44:41 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:44:41 volumio volumio[3737]: Cannot find translation for source RADIO 357
Apr 30 19:44:41 volumio volumio[3737]: Cannot find translation for source Radio Paradise
Apr 30 19:44:41 volumio volumio[3737]: info: Volumio Calling Home
Apr 30 19:44:42 volumio volumio[3737]: info: Preparing to generate the ALSA configuration file
Apr 30 19:44:42 volumio volumio[3737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 30 19:44:42 volumio sudo[3928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:44:42 volumio volumio[3737]: info: Reading ALSA contributions from plugins.
Apr 30 19:44:42 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:42 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:42 volumio sudo[3930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:44:42 volumio volumio[3737]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4
Apr 30 19:44:42 volumio sudo[3930]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:42 volumio sudo[3930]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:42 volumio volumio[3737]: info: MPD Permissions set
Apr 30 19:44:42 volumio volumio[3737]: info: MPD Permissions set
Apr 30 19:44:42 volumio volumio-remote-updater[466]: [2025-04-30 19:44:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746035081 101
Apr 30 19:44:42 volumio volumio[3737]: 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: 5
Apr 30 19:44:42 volumio volumio[3737]: info: Volumio called home
Apr 30 19:44:42 volumio volumio[3737]: info: Spotify config file written
Apr 30 19:44:42 volumio sudo[3936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Apr 30 19:44:42 volumio sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:42 volumio systemd[1]: Stopping go-librespot Daemon...
Apr 30 19:44:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 30 19:44:42 volumio systemd[1]: go-librespot-daemon.service: Succeeded.
Apr 30 19:44:42 volumio systemd[1]: Stopped go-librespot Daemon.
Apr 30 19:44:42 volumio systemd[1]: Started go-librespot Daemon.
Apr 30 19:44:42 volumio go-librespot[3938]: Librespot-go daemon starting...
Apr 30 19:44:42 volumio sudo[3936]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:42 volumio go-librespot[3938]: time="2025-04-30T19:44:42+02:00" level=info msg="generated new device id: c555fe8a43e9ddec25a00c188bd1979baf166ef8"
Apr 30 19:44:42 volumio go-librespot[3938]: time="2025-04-30T19:44:42+02:00" level=debug msg="stored credentials found for fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:42 volumio volumio[3737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Apr 30 19:44:42 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:42 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio go-librespot[3938]: time="2025-04-30T19:44:43+02:00" level=debug msg="obtained new client token: AAA7inM7D3iJDIQxEzbiYbXLh7HrCdeoa/VqAWNda1V2Yb+oa/H+WRgAOJHHun9v4cVnfQUk1kMGGFRgPtEHr8FH9CUiNoWclzd/AI0V7acwcEy+Q7bsCL/m6adldPW+IXY99HAPVYuTJ3E9OLmsq//UMRldpN9frcPPmb1uQi1Up0P+uP+jQu7DO0NUzXMP/4sWG6OcO6VogSTqTj940Mg4lsBN9ZKMMC47UqBnLUFDFfy947QRGwGFLCRoyA=="
Apr 30 19:44:43 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 96.
Apr 30 19:44:43 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:43 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:43 volumio go-librespot[3938]: time="2025-04-30T19:44:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Apr 30 19:44:43 volumio volumio[3737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 30 19:44:43 volumio volumio[3737]: SPOTIFY: BQCK7B9oGFpqfA0JVbHtgq5sfH7hMA9FiGRrkMq-SjoIUi4j-hibBPZ9D-1EAC1YZJFGbUHu_Xvek4kLTTxoadpagLeoxv1x_yjYeGtUci8JFmvt822Bdbb5fqOibkQ2_k4E3DjxkxiFJaEzrrJif7OtxJAxjIO57e_YmJbs5X0SnrNvnbgwwJMBdcnh5WxwuSJC-5XOUJkKCVljUUU-kupUaY_jzN43MmoEYbFvRCJ4NfVDs4o-ZLYPVdCptZWe5AErvxLrBXkLykJiWfX4zpZyIPH-z1915ijAj_uvtSrUixw
Apr 30 19:44:43 volumio volumio[3737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Apr 30 19:44:43 volumio volumio[3737]: info: New Spotify access token = BQCK7B9oGFpqfA0JVbHtgq5sfH7hMA9FiGRrkMq-SjoIUi4j-hibBPZ9D-1EAC1YZJFGbUHu_Xvek4kLTTxoadpagLeoxv1x_yjYeGtUci8JFmvt822Bdbb5fqOibkQ2_k4E3DjxkxiFJaEzrrJif7OtxJAxjIO57e_YmJbs5X0SnrNvnbgwwJMBdcnh5WxwuSJC-5XOUJkKCVljUUU-kupUaY_jzN43MmoEYbFvRCJ4NfVDs4o-ZLYPVdCptZWe5AErvxLrBXkLykJiWfX4zpZyIPH-z1915ijAj_uvtSrUixw
Apr 30 19:44:43 volumio volumio[3956]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:43 volumio volumio[3737]: info: Spotify credentials grant success - running version from March 24, 2019
Apr 30 19:44:43 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:43 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:43 volumio go-librespot[3938]: time="2025-04-30T19:44:43+02:00" level=debug msg="completed keyexchange"
Apr 30 19:44:43 volumio volumio[3737]: info: Starting Shairport Sync
Apr 30 19:44:43 volumio volumio[3737]: info: Starting Shairport Sync
Apr 30 19:44:43 volumio volumio[3737]: info: Starting Shairport Sync
Apr 30 19:44:43 volumio sudo[3958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:43 volumio sudo[3958]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:43 volumio sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:43 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 30 19:44:43 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 30 19:44:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::volumioGetState
Apr 30 19:44:43 volumio volumio[3737]: info: CorePlayQueue::getTrack 0
Apr 30 19:44:43 volumio sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 30 19:44:43 volumio sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:43 volumio volumio[3737]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:43 volumio volumio[3737]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo
Apr 30 19:44:43 volumio volumio[3737]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 30 19:44:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:43 volumio volumio[3737]: info: camilladsp service started and running in background, instance 1
Apr 30 19:44:43 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:44:43 volumio sudo[3958]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:43 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:43 volumio volumio[3737]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:43 volumio volumio[3737]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Apr 30 19:44:43 volumio volumio[3737]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found
Apr 30 19:44:44 volumio volumio[3737]: info: FusionDsp loaded
Apr 30 19:44:44 volumio volumio[3737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 19:44:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 30 19:44:44 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 30 19:44:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:44 volumio sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 30 19:44:44 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:44 volumio volumio[3737]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 30 19:44:44 volumio volumio[3737]: info: Adding Signal Path Element [object Object]
Apr 30 19:44:44 volumio volumio[3737]: info: Adding fusiondspeq DSP Signal Path Element
Apr 30 19:44:44 volumio volumio[3737]: info: FusionDsp - ---- installed callbackRead
Apr 30 19:44:44 volumio volumio[3737]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="completed challenge"
Apr 30 19:44:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 30 19:44:44 volumio sudo[3963]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="authenticated as fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:44 volumio volumio[3737]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Apr 30 19:44:44 volumio volumio[3737]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19)
Apr 30 19:44:44 volumio volumio[3737]: at onErrorNT (internal/child_process.js:465:16)
Apr 30 19:44:44 volumio volumio[3737]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 30 19:44:44 volumio volumio[3737]: at runNextTicks (internal/process/task_queues.js:62:3)
Apr 30 19:44:44 volumio volumio[3737]: at listOnTimeout (internal/timers.js:523:9)
Apr 30 19:44:44 volumio volumio[3737]: at processTimers (internal/timers.js:497:7) {
Apr 30 19:44:44 volumio volumio[3737]: errno: -2,
Apr 30 19:44:44 volumio volumio[3737]: code: 'ENOENT',
Apr 30 19:44:44 volumio volumio[3737]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:44 volumio volumio[3737]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Apr 30 19:44:44 volumio volumio[3737]: spawnargs: [
Apr 30 19:44:44 volumio volumio[3737]: '-p',
Apr 30 19:44:44 volumio volumio[3737]: 9876,
Apr 30 19:44:44 volumio volumio[3737]: '-o',
Apr 30 19:44:44 volumio volumio[3737]: '/tmp/camilladsp.log',
Apr 30 19:44:44 volumio volumio[3737]: '-l',
Apr 30 19:44:44 volumio volumio[3737]: 'warn',
Apr 30 19:44:44 volumio volumio[3737]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Apr 30 19:44:44 volumio volumio[3737]: ]
Apr 30 19:44:44 volumio volumio[3737]: }
Apr 30 19:44:44 volumio volumio[3737]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="authenticated as fzzgrc02f3abp6mmrlz3bopik"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="dealer connection opened"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="received connection id: MzkwMThmNWMtN2Y2Ny00ZGNmLThjYTYtZDlhZTUxMTFiN2ZlK2RlYWxlcit0Y3A6Ly8wYWNhNThkMS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRTFCMjM3QzE5NUQxNUY2MEZFMjU1RDJGODVBODQ4Q0JBRjVCMDZDRjlFQ0Y0MDNFRjQwQkUxRDE1OTcxRDM1NA=="
Apr 30 19:44:44 volumio mpd[3923]: Apr 30 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 30 19:44:44 volumio systemd[1]: Started Music Player Daemon.
Apr 30 19:44:44 volumio sudo[3887]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:44 volumio sudo[3877]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:44 volumio go-librespot[3938]: time="2025-04-30T19:44:44+02:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 30 19:44:45 volumio sudo[3994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 19:43
Apr 30 19:44:45 volumio sudo[3994]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:45 volumio sudo[3994]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:45 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 97.
Apr 30 19:44:45 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:45 volumio volumio-remote-updater[466]: [2025-04-30 19:44:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 30 19:44:45 volumio volumio-remote-updater[466]: [2025-04-30 19:44:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 30 19:44:45 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 30 19:44:45 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 30 19:44:45 volumio systemd[1]: Started dynamicswap service.
Apr 30 19:44:46 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 30 19:44:46 volumio volumio[3999]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 30 19:44:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11.
Apr 30 19:44:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:46 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 30 19:44:46 volumio systemd[1]: Started Volumio Backend Module.
Apr 30 19:44:46 volumio systemd[1]: Started dynamicswap service.
Apr 30 19:44:46 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 30 19:44:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 98.
Apr 30 19:44:48 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:48 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:48 volumio volumio[4021]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:49 volumio volumio[4008]: info: -------------------------------------------
Apr 30 19:44:49 volumio volumio[4008]: info: ----- Volumio3 ----
Apr 30 19:44:49 volumio volumio[4008]: info: -------------------------------------------
Apr 30 19:44:49 volumio volumio[4008]: info: ----- System startup ----
Apr 30 19:44:49 volumio volumio[4008]: info: -------------------------------------------
Apr 30 19:44:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 99.
Apr 30 19:44:50 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:50 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:50 volumio volumio-remote-updater[466]: [2025-04-30 19:44:50] [connect] Successful connection
Apr 30 19:44:50 volumio volumio[4030]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:51 volumio volumio[4008]: info: MYVOLUMIO Environment detected
Apr 30 19:44:51 volumio volumio[4008]: info: Plugin folders cleanup
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning into folder /volumio/app/plugins/
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category audio_interface
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category miscellanea
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category music_service
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category plugins.json
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category system_controller
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category user_interface
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning into folder /data/plugins/
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category audio_interface
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category music_service
Apr 30 19:44:51 volumio volumio[4008]: info: Scanning category system_controller
Apr 30 19:44:51 volumio volumio[4008]: info: Plugin folders cleanup completed
Apr 30 19:44:51 volumio volumio[4008]: info: -------------------------------------------
Apr 30 19:44:51 volumio volumio[4008]: info: ----- Core plugins startup ----
Apr 30 19:44:51 volumio volumio[4008]: info: -------------------------------------------
Apr 30 19:44:51 volumio volumio[4008]: info: Loading plugins from folder /volumio/app/plugins/
Apr 30 19:44:51 volumio volumio[4008]: info: Adding plugin upnp to MyMusic Plugins
Apr 30 19:44:51 volumio volumio[4008]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 30 19:44:51 volumio volumio[4008]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 30 19:44:51 volumio volumio[4008]: info: Loading plugins from folder /data/plugins/
Apr 30 19:44:51 volumio volumio[4008]: info: Loading plugin "system"...
Apr 30 19:44:51 volumio volumio[4008]: info: Loading plugin "appearance"...
Apr 30 19:44:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 100.
Apr 30 19:44:53 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:53 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "network"...
Apr 30 19:44:53 volumio volumio[4008]: info: Refreshing Cached IP Addresses
Apr 30 19:44:53 volumio sudo[4038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:44:53 volumio sudo[4038]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:53 volumio sudo[4040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:44:53 volumio sudo[4040]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:53 volumio sudo[4038]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "services"...
Apr 30 19:44:53 volumio volumio[4036]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "alsa_controller"...
Apr 30 19:44:53 volumio sudo[4040]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:53 volumio sudo[4050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 30 19:44:53 volumio sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:44:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:53 volumio sudo[4050]: pam_unix(sudo:session): session closed for user root
Apr 30 19:44:53 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "wizard"...
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "networkfs"...
Apr 30 19:44:53 volumio volumio[4008]: info: Starting Udev Watcher for removable devices
Apr 30 19:44:53 volumio volumio[4008]: info: Ignoring mount for partition: boot
Apr 30 19:44:53 volumio volumio[4008]: info: Ignoring mount for partition: volumio
Apr 30 19:44:53 volumio volumio[4008]: info: Ignoring mount for partition: volumio_data
Apr 30 19:44:53 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "volumio_command_line_client"...
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "upnp"...
Apr 30 19:44:53 volumio volumio[4008]: info: [1746035093685] Starting Upmpd Daemon
Apr 30 19:44:53 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "my_music"...
Apr 30 19:44:53 volumio volumio[4008]: info: Loading plugin "mpd"...
Apr 30 19:44:54 volumio volumio[4008]: info: Loading plugin "upnp_browser"...
Apr 30 19:44:54 volumio volumio[4008]: info: Loading plugin "alarm-clock"...
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "airplay_emulation"...
Apr 30 19:44:55 volumio volumio[4008]: info: Starting Shairport Sync
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "last_100"...
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "webradio"...
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "i2s_dacs"...
Apr 30 19:44:55 volumio volumio[4008]: info: I2S DAC not set, start Auto-detection
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "volumiodiscovery"...
Apr 30 19:44:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 101.
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** For more information see
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:55 volumio volumio[4008]: *** WARNING *** For more information see
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** For more information see
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 30 19:44:55 volumio node[4008]: *** WARNING *** For more information see
Apr 30 19:44:55 volumio volumio[4008]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 30 19:44:55 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:55 volumio volumio[4008]: info: Discovery: Started advertising with name: Volumio
Apr 30 19:44:55 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 30 19:44:55 volumio volumio[4008]: info: Loading plugin "spop"...
Apr 30 19:44:55 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:55 volumio volumio[4069]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:57 volumio volumio[4008]: info: Loading plugin "autostart"...
Apr 30 19:44:58 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:44:58 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 102.
Apr 30 19:44:58 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:44:58 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:44:58 volumio volumio[4008]: info: Applying required configuration parameters for plugin autostart
Apr 30 19:44:58 volumio volumio[4008]: info: AutoStart - onVolumioStart - read config.json
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "outputs"...
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "albumart"...
Apr 30 19:44:58 volumio volumio[4008]: info: Plugin example_plugin is not enabled
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "inputs"...
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "updater_comm"...
Apr 30 19:44:58 volumio volumio[4075]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:44:58 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:44:58 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:44:58 volumio volumio[4008]: info: Plugin mpdemulation is not enabled
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "rest_api"...
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "websocket"...
Apr 30 19:44:58 volumio volumio[4008]: info: Starting Socket.io Server version 2.3.0
Apr 30 19:44:58 volumio volumio[4008]: info: Loading plugin "fusiondsp"...
Apr 30 19:44:59 volumio volumio[4008]: Forking 3 albumart workers
Apr 30 19:44:59 volumio volumio[4008]: info: Applying required configuration parameters for plugin fusiondsp
Apr 30 19:44:59 volumio volumio[4008]: info: Loading plugin "rad357pl"...
Apr 30 19:45:00 volumio volumio[4008]: info: Applying required configuration parameters for plugin rad357pl
Apr 30 19:45:00 volumio volumio[4008]: info: [1746035100101] [rad357pl] API delay: 5
Apr 30 19:45:00 volumio volumio[4008]: info: Loading plugin "radio_paradise"...
Apr 30 19:45:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:45:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 103.
Apr 30 19:45:00 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:45:00 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:45:00 volumio volumio[4104]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:45:00 volumio volumio[4008]: info: Applying required configuration parameters for plugin radio_paradise
Apr 30 19:45:00 volumio volumio[4008]: info: [1746035100839] [RadioParadise] API delay: 5
Apr 30 19:45:00 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:45:00 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:45:00 volumio volumio[4008]: info: Loading i18n strings for locale pl
Apr 30 19:45:00 volumio volumio[4008]: Updating browse sources language
Apr 30 19:45:00 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:45:01 volumio volumio[4008]: Starting albumart workers
Apr 30 19:45:01 volumio volumio[4008]: Starting albumart workers
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::initPlayerControls
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: Express server listening on port 3000
Apr 30 19:45:01 volumio volumio[4008]: [Metrics] WebUI: 12s 788.76ms
Apr 30 19:45:01 volumio volumio[4008]: Starting albumart workers
Apr 30 19:45:01 volumio volumio[4008]: info: CoreStateMachine::resetVolumioState
Apr 30 19:45:01 volumio volumio[4008]: info: CoreStateMachine::getcurrentVolume
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:45:01 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 19:45:01 volumio volumio[4008]: info: Volumio Network Manager: Network status updated: 1
Apr 30 19:45:01 volumio volumio[4008]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Apr 30 19:45:01 volumio volumio[4008]: wlan0 Interface doesn't support scanning.
Apr 30 19:45:01 volumio volumio[4008]: info: Cannot use regular scanning, forcing with ap-force
Apr 30 19:45:01 volumio sudo[4110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Apr 30 19:45:01 volumio sudo[4110]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:45:01 volumio sudo[4110]: pam_unix(sudo:session): session closed for user root
Apr 30 19:45:01 volumio volumio[4008]: command failed: No such device (-19)
Apr 30 19:45:01 volumio volumio[4008]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Apr 30 19:45:01 volumio volumio[4008]: command failed: No such device (-19)
Apr 30 19:45:02 volumio volumio[4008]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1
Apr 30 19:45:02 volumio volumio[4008]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2
Apr 30 19:45:02 volumio volumio[4008]: info: Reloading queue from file
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::setRepeat null single undefined
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::pushState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioPushState
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::setRandom null
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::pushState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioPushState
Apr 30 19:45:02 volumio volumio[4008]: info: Setting Device type: Raspberry PI
Apr 30 19:45:02 volumio volumio[4008]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::pushState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioPushState
Apr 30 19:45:02 volumio volumio[4008]: info: CoreStateMachine::updateTrackBlock
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrackBlock
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioRetrievevolume
Apr 30 19:45:02 volumio volumio[4008]: verbose: New Socket.io Connection to 192.168.178.55 from 192.168.178.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.4 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioGetState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 30 19:45:02 volumio volumio[4008]: info: Received Get System Info
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 19:45:02 volumio volumio[4008]: info: Discovery: Getting this device information
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioGetState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 19:45:02 volumio volumio[4008]: info: CoreCommandRouter::volumioGetState
Apr 30 19:45:02 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:02 volumio volumio[4008]: info: Listing playlists
Apr 30 19:45:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:45:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 104.
Apr 30 19:45:03 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:45:03 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:45:03 volumio volumio[4008]: info: Discovery: adding c22eb07e-309a-4cf9-83fc-cae3e886494f
Apr 30 19:45:03 volumio volumio[4008]: info: Discovery: Found device Volumio
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::volumioGetState
Apr 30 19:45:03 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:03 volumio volumio[4008]: info: Completed loading Core Plugins
Apr 30 19:45:03 volumio volumio[4008]: info: Preparing to generate the ALSA configuration file
Apr 30 19:45:03 volumio volumio[4146]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:45:03 volumio volumio[4008]: info: VolumeController:: Volume=93 Mute =false
Apr 30 19:45:03 volumio volumio[4008]: info: CoreStateMachine::pushState
Apr 30 19:45:03 volumio volumio[4008]: info: CorePlayQueue::getTrack 0
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::volumioPushState
Apr 30 19:45:03 volumio volumio[4008]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 30 19:45:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:45:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:45:03 volumio volumio[4008]: info: Reading ALSA contributions from plugins.
Apr 30 19:45:03 volumio volumio-remote-updater[466]: [2025-04-30 19:45:03] [connect] Successful connection
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio[4008]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 30 19:45:03 volumio volumio-remote-updater[466]: [2025-04-30 19:45:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746035103 101
Apr 30 19:45:03 volumio volumio[4008]: 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: 4
Apr 30 19:45:03 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:04 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:04 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:04 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:05 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:45:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 105.
Apr 30 19:45:05 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:45:05 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:45:05 volumio volumio[4162]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:45:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:45:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:45:05 volumio volumio[4008]: info: FusionDsp -
Apr 30 19:45:06 volumio sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:06 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:45:06 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root
Apr 30 19:45:06 volumio sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 19:45:06 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 19:45:06 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:06 volumio volumio[4008]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
Apr 30 19:45:07 volumio volumio[4008]: info: CoreCommandRouter::Close All Modals sent
Apr 30 19:45:07 volumio volumio[4008]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:45:07 volumio volumio[4008]: TypeError: Cannot read property 'off' of undefined
Apr 30 19:45:07 volumio volumio[4008]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15)
Apr 30 19:45:07 volumio volumio[4008]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30)
Apr 30 19:45:07 volumio volumio[4008]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8)
Apr 30 19:45:07 volumio volumio[4008]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1550:29)
Apr 30 19:45:07 volumio volumio[4008]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47)
Apr 30 19:45:07 volumio volumio[4008]: at Socket.emit (events.js:315:20)
Apr 30 19:45:07 volumio volumio[4008]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Apr 30 19:45:07 volumio volumio[4008]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 30 19:45:07 volumio volumio[4008]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 19:45:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart.
Apr 30 19:45:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 106.
Apr 30 19:45:08 volumio systemd[1]: Stopped FusionDsp Daemon.
Apr 30 19:45:08 volumio systemd[1]: Started FusionDsp Daemon.
Apr 30 19:45:08 volumio volumio[4182]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory
Apr 30 19:45:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 30 19:45:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'.
Apr 30 19:45:08 volumio sudo[4184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 19:44
Apr 30 19:45:08 volumio sudo[4184]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"