-- Logs begin at Fri 2025-07-04 13:42:07 UTC, end at Fri 2025-07-04 13:57:59 UTC. -- Jul 04 13:56:02 volumio volumio[1045]: info: Collecting websocket_client (from camilladsp==1.0.0) Jul 04 13:56:02 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Jul 04 13:56:02 volumio volumio[1045]: info: Building wheels for collected packages: camilladsp, PyYAML Jul 04 13:56:02 volumio volumio[1045]: info: Running setup.py bdist_wheel for camilladsp: started Jul 04 13:56:03 volumio volumio[1045]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jul 04 13:56:03 volumio volumio[1045]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-esdgsqim/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jul 04 13:56:03 volumio volumio[1045]: info: Running setup.py bdist_wheel for PyYAML: started Jul 04 13:56:06 volumio volumio[1045]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' Jul 04 13:56:06 volumio volumio[1045]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 Jul 04 13:56:06 volumio volumio[1045]: info: Successfully built camilladsp PyYAML Jul 04 13:56:06 volumio volumio[1045]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Jul 04 13:56:06 volumio volumio[1045]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Jul 04 13:56:10 volumio volumio[1045]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jul 04 13:56:10 volumio volumio[1045]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-6wgahyxb Jul 04 13:56:13 volumio volumio[1045]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jul 04 13:56:13 volumio volumio[1045]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Jul 04 13:56:14 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Jul 04 13:56:14 volumio volumio[1045]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:15 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Jul 04 13:56:15 volumio volumio[1045]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:15 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Jul 04 13:56:15 volumio volumio[1045]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:16 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Jul 04 13:56:16 volumio volumio[1045]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jul 04 13:56:16 volumio volumio[1045]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:16 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Jul 04 13:56:17 volumio volumio[1045]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:17 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Jul 04 13:56:17 volumio volumio[1045]: info: Collecting zipp>=0.5 (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) Jul 04 13:56:17 volumio volumio[1045]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Jul 04 13:56:18 volumio volumio[1045]: info: Building wheels for collected packages: camilladsp-plot Jul 04 13:56:18 volumio volumio[1045]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jul 04 13:56:19 volumio volumio[1045]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jul 04 13:56:19 volumio volumio[1045]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-66zuahvq/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jul 04 13:56:19 volumio volumio[1045]: info: Successfully built camilladsp-plot Jul 04 13:56:19 volumio volumio[1045]: info: Installing collected packages: typing-extensions, zipp, importlib-metadata, pkgutil-resolve-name, pyrsistent, importlib-resources, jsonschema, camilladsp-plot Jul 04 13:56:20 volumio volumio[1045]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Jul 04 13:56:20 volumio volumio[1045]: info: copying hw detection script Jul 04 13:56:20 volumio volumio[1045]: info: Detected cpu architecture as x86_64 Jul 04 13:56:24 volumio sudo[6674]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jul 04 13:56:24 volumio sudo[6674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:24 volumio volumio[1045]: info: Reading package lists... Jul 04 13:56:25 volumio volumio[1045]: info: Building dependency tree... Jul 04 13:56:25 volumio volumio[1045]: info: Reading state information... Jul 04 13:56:26 volumio volumio[1045]: info: The following NEW packages will be installed: Jul 04 13:56:26 volumio volumio[1045]: info: drc Jul 04 13:56:26 volumio volumio[1045]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Jul 04 13:56:26 volumio volumio[1045]: info: Need to get 143 kB of archives. Jul 04 13:56:26 volumio volumio[1045]: info: After this operation, 609 kB of additional disk space will be used. Jul 04 13:56:26 volumio volumio[1045]: info: Get:1 http://deb.debian.org/debian buster/main amd64 drc amd64 3.2.2~dfsg0-2 [143 kB] Jul 04 13:56:27 volumio volumio[1045]: info: Fetched 143 kB in 0s (576 kB/s) Jul 04 13:56:27 volumio volumio[1045]: info: Selecting previously unselected package drc. Jul 04 13:56:28 volumio volumio[1045]: [620B blob data] Jul 04 13:56:28 volumio volumio[1045]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_amd64.deb ... Jul 04 13:56:28 volumio volumio[1045]: info: Unpacking drc (3.2.2~dfsg0-2) ... Jul 04 13:56:29 volumio volumio[1045]: info: Setting up drc (3.2.2~dfsg0-2) ... Jul 04 13:56:29 volumio volumio[1045]: info: Processing triggers for man-db (2.8.5-2) ... Jul 04 13:56:30 volumio sudo[6674]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:30 volumio sudo[4779]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:30 volumio volumio[1045]: Plugin install end detected on script Jul 04 13:56:30 volumio volumio[1045]: info: Install script completed Jul 04 13:56:30 volumio volumio[1045]: info: Adding reference to registry Jul 04 13:56:30 volumio volumio[1045]: info: Done installing plugin. Jul 04 13:56:30 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 13:56:30 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 13:56:30 volumio volumio[1045]: info: Folder /tmp/plugins removed Jul 04 13:56:30 volumio volumio[1045]: info: Folder /tmp/downloaded_plugin.zip removed Jul 04 13:56:30 volumio volumio[1045]: info: Folder /data/temp removed Jul 04 13:56:33 volumio volumio[1045]: info: Enabling plugin fusiondsp Jul 04 13:56:33 volumio volumio[1045]: info: Loading plugin "fusiondsp"... Jul 04 13:56:34 volumio volumio[1045]: info: Preparing to generate the ALSA configuration file Jul 04 13:56:34 volumio volumio[1045]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 13:56:34 volumio volumio[1045]: info: Reading ALSA contributions from plugins. Jul 04 13:56:34 volumio volumio[1045]: info: Asound.conf file written Jul 04 13:56:34 volumio sudo[6748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 04 13:56:34 volumio sudo[6748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio sudo[6748]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:34 volumio volumio[1045]: No state is present for card Audio Jul 04 13:56:34 volumio volumio[1045]: Found hardware: "HdmiLpeAudio" "" "" "" "" Jul 04 13:56:34 volumio volumio[1045]: Hardware is initialized using a generic method Jul 04 13:56:34 volumio volumio[1045]: No state is present for card Audio Jul 04 13:56:34 volumio volumio[1045]: No state is present for card bytchtnocodec Jul 04 13:56:34 volumio volumio[1045]: Found hardware: "bytcht-nocodec" "" "" "" "" Jul 04 13:56:34 volumio volumio[1045]: Hardware is initialized using a generic method Jul 04 13:56:34 volumio volumio[1045]: No state is present for card bytchtnocodec Jul 04 13:56:34 volumio volumio[1045]: info: Output device has changed, restarting MPD Jul 04 13:56:34 volumio volumio[1045]: info: Output device has changed, restarting Shairport Sync Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:56:34 volumio sudo[6754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 13:56:34 volumio sudo[6754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio sudo[6754]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:34 volumio sudo[6756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 13:56:34 volumio sudo[6756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio systemd[1]: Stopping Music Player Daemon... Jul 04 13:56:34 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 13:56:34 volumio volumio[1045]: info: PLUGIN START: fusiondsp Jul 04 13:56:34 volumio volumio[1045]: info: Loading i18n strings for locale ru Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: FusionDsp - mixtype--------------------- None Jul 04 13:56:34 volumio volumio[1045]: info: Preparing to generate the ALSA configuration file Jul 04 13:56:34 volumio volumio[1045]: info: Done. Jul 04 13:56:34 volumio volumio[1045]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 04 13:56:34 volumio volumio[1045]: info: Reading ALSA contributions from plugins. Jul 04 13:56:34 volumio volumio[1045]: info: MPD Permissions set Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:56:34 volumio volumio[1045]: info: Asound.conf file unchanged, so no further update is needed Jul 04 13:56:34 volumio volumio[1045]: info: Output device has changed, restarting MPD Jul 04 13:56:34 volumio sudo[6770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 13:56:34 volumio sudo[6770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio volumio[1045]: info: Output device has changed, restarting Shairport Sync Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:56:34 volumio sudo[6770]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:34 volumio sudo[6773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 13:56:34 volumio sudo[6773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 13:56:34 volumio volumio[1045]: info: MPD Permissions set Jul 04 13:56:34 volumio volumio[1045]: info: Starting Shairport Sync Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio sudo[6779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 13:56:34 volumio sudo[6779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 04 13:56:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 13:56:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 13:56:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 13:56:34 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 13:56:34 volumio sudo[6779]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:34 volumio volumio[1045]: info: FusionDsp - Jul 04 13:56:34 volumio volumio[1045]: info: FusionDsp - undefined Jul 04 13:56:34 volumio volumio[1045]: info: Shairport-Sync Started Jul 04 13:56:34 volumio volumio[1045]: info: Starting Shairport Sync Jul 04 13:56:34 volumio sudo[6791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 13:56:34 volumio sudo[6791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 13:56:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 04 13:56:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 13:56:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 13:56:35 volumio sudo[6791]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:35 volumio volumio[1045]: info: Shairport-Sync Started Jul 04 13:56:35 volumio systemd[1]: mpd.service: Succeeded. Jul 04 13:56:35 volumio systemd[1]: Stopped Music Player Daemon. Jul 04 13:56:35 volumio systemd[1]: Starting Music Player Daemon... Jul 04 13:56:35 volumio sudo[6796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 13:56:35 volumio sudo[6796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:35 volumio sudo[6796]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:36 volumio volumio[1045]: info: camilladsp service started and running in background, instance 1 Jul 04 13:56:36 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 13:56:36 volumio volumio[1045]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jul 04 13:56:36 volumio volumio[1045]: info: FusionDsp loaded Jul 04 13:56:36 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 13:56:36 volumio sudo[6812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 04 13:56:36 volumio sudo[6812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 13:56:36 volumio volumio[1045]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 04 13:56:36 volumio volumio[1045]: info: Adding Signal Path Element [object Object] Jul 04 13:56:36 volumio volumio[1045]: info: Adding fusiondspeq DSP Signal Path Element Jul 04 13:56:36 volumio volumio[1045]: info: FusionDsp - ---- installed callbackRead Jul 04 13:56:36 volumio volumio[1045]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 04 13:56:36 volumio systemd[1]: Started FusionDsp Daemon. Jul 04 13:56:36 volumio volumio[1045]: error: FusionDsp - WebSocket error: [object Object] Jul 04 13:56:36 volumio volumio[1045]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 04 13:56:36 volumio sudo[6812]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:36 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 13:56:36 volumio mpd[6799]: Jul 04 13:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 13:56:36 volumio systemd[1]: Started Music Player Daemon. Jul 04 13:56:36 volumio sudo[6756]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:36 volumio sudo[6773]: pam_unix(sudo:session): session closed for user root Jul 04 13:56:37 volumio volumio[1045]: error: MPD error: The expression evaluated to a falsy value: Jul 04 13:56:37 volumio volumio[1045]: assert.ok(self.idling) Jul 04 13:56:37 volumio volumio[1045]: error: The expression evaluated to a falsy value: Jul 04 13:56:37 volumio volumio[1045]: assert.ok(self.idling) Jul 04 13:56:37 volumio volumio[1045]: error: updateQueue error: null Jul 04 13:56:37 volumio volumio[1045]: 0+0 records in Jul 04 13:56:37 volumio volumio[1045]: 0+0 records out Jul 04 13:56:37 volumio volumio[1045]: 0 bytes copied, 0.000402094 s, 0.0 kB/s Jul 04 13:56:43 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 04 13:56:43 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:56:45 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 13:56:45 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 13:56:50 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:56:50 volumio volumio[1045]: info: FusionDsp - Jul 04 13:56:50 volumio volumio[1045]: info: FusionDsp - undefined Jul 04 13:56:54 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 04 13:56:54 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:56:57 volumio volumio[1045]: info: CoreCommandRouter::volumioGetQueue Jul 04 13:56:57 volumio volumio[1045]: info: CoreStateMachine::getQueue Jul 04 13:56:57 volumio volumio[1045]: info: CorePlayQueue::getQueue Jul 04 13:57:02 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:02 volumio volumio[1045]: info: CURURI: music-library Jul 04 13:57:02 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:04 volumio volumio[1045]: info: CURURI: music-library/NAS Jul 04 13:57:04 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:04 volumio volumio[1045]: info: CURURI: music-library/NAS/Media_3G Jul 04 13:57:05 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:05 volumio volumio[1045]: info: CURURI: music-library/NAS/Media_3G/Maryia Jul 04 13:57:05 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:05 volumio volumio[1045]: info: Preloading song: music-library/NAS/Media_3G/Maryia/Crna macka, beli macor (Black cat, White cat. Чёрная кошка, белый кот). 1998.mkv Jul 04 13:57:06 volumio volumio[1045]: info: Exploding uri music-library/NAS/Media_3G/Maryia/Crna macka, beli macor (Black cat, White cat. Чёрная кошка, белый кот). 1998.mkv in service mpd Jul 04 13:57:07 volumio volumio[1045]: info: ALBUMART /albumart?cacheid=251&web=//extralarge&path=%2Fmnt%2FNAS%2FMedia_3G%2FMaryia%2FCrna%20macka%2C%20beli%20macor%20(Black%20cat%2C%20White%20cat.%20%D0%A7%D1%91%D1%80%D0%BD%D0%B0%D1%8F%20%D0%BA%D0%BE%D1%88%D0%BA%D0%B0%2C%20%D0%B1%D0%B5%D0%BB%D1%8B%D0%B9%20%D0%BA%D0%BE%D1%82).%201998.mkv&metadata=false Jul 04 13:57:07 volumio volumio[1045]: info: URI /mnt/NAS/Media_3G/Maryia/Crna macka, beli macor (Black cat, White cat. Чёрная кошка, белый кот). 1998.mkv Jul 04 13:57:12 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 04 13:57:12 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 13:57:12 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jul 04 13:57:12 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jul 04 13:57:12 volumio systemd[1]: Started Cleanup of Temporary Directories. Jul 04 13:57:13 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:15 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 04 13:57:15 volumio volumio[1045]: info: TuneIn handleBrowseUri: tunein/local Jul 04 13:57:15 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:17 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::ClearQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::stop Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::clearPlayQueue Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::saveQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPushQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::addQueueItems Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::addQueueItems Jul 04 13:57:17 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:17 volumio volumio[1045]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s300318 Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 04 13:57:17 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::ClearQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::stop Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::clearPlayQueue Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::saveQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPushQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::addQueueItems Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::addQueueItems Jul 04 13:57:17 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:17 volumio volumio[1045]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s300318 Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPushQueue Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::saveQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPushQueue Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::saveQueue Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::updateTrackBlock Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrackBlock Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::updateTrackBlock Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrackBlock Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPlay Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::play index 0 Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreCommandRouter::volumioPlay Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::play index 0 Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::stop Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::stop Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::play index undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::play index undefined Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::startPlaybackTimer Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:17 volumio volumio[1045]: info: [1751637437516] ControllerWebradio::clearAddPlayTrack Jul 04 13:57:17 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:17 volumio volumio[1045]: info: CoreStateMachine::startPlaybackTimer Jul 04 13:57:17 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:17 volumio volumio[1045]: info: [1751637437519] ControllerWebradio::clearAddPlayTrack Jul 04 13:57:17 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand stop Jul 04 13:57:17 volumio volumio[1045]: info: sendMpdCommand stop took 9 milliseconds Jul 04 13:57:17 volumio volumio[1045]: info: sendMpdCommand stop took 8 milliseconds Jul 04 13:57:17 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 13:57:17 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand clear Jul 04 13:57:17 volumio volumio[1045]: info: Jul 04 13:57:17 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:17 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:17 volumio volumio[1045]: info: Jul 04 13:57:17 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:17 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:17 volumio volumio[1045]: info: sendMpdCommand clear took 4 milliseconds Jul 04 13:57:17 volumio volumio[1045]: info: sendMpdCommand clear took 5 milliseconds Jul 04 13:57:17 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand load "http://live.avtoradio.by:8000/avtoradio" Jul 04 13:57:17 volumio volumio[1045]: info: Jul 04 13:57:17 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:17 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:17 volumio volumio[1045]: error: updateQueue error: null Jul 04 13:57:17 volumio volumio[1045]: error: updateQueue error: null Jul 04 13:57:17 volumio volumio[1045]: info: ------------------------------ 9ms Jul 04 13:57:17 volumio volumio[1045]: info: ------------------------------ 8ms Jul 04 13:57:18 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://live.avtoradio.by:8000/avtoradio" Jul 04 13:57:18 volumio volumio[1045]: info: Jul 04 13:57:18 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:18 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:18 volumio volumio[1045]: error: updateQueue error: null Jul 04 13:57:18 volumio volumio[1045]: info: Jul 04 13:57:18 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:18 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:18 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand add "http://live.avtoradio.by:8000/avtoradio" Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 1219ms Jul 04 13:57:18 volumio volumio[1045]: info: sendMpdCommand add "http://live.avtoradio.by:8000/avtoradio" took 287 milliseconds Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 12ms Jul 04 13:57:18 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 13:57:18 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Jul 04 13:57:18 volumio volumio[1045]: info: Jul 04 13:57:18 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:18 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:18 volumio volumio[1045]: info: Jul 04 13:57:18 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:18 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:18 volumio volumio[1045]: info: sendMpdCommand add "http://live.avtoradio.by:8000/avtoradio" took 14 milliseconds Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 18ms Jul 04 13:57:18 volumio volumio[1045]: info: sendMpdCommand play took 9 milliseconds Jul 04 13:57:18 volumio volumio[1045]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 04 13:57:18 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand play Jul 04 13:57:18 volumio volumio[1045]: info: Jul 04 13:57:18 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:18 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 15ms Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 14ms Jul 04 13:57:18 volumio volumio[1045]: info: sendMpdCommand play took 7 milliseconds Jul 04 13:57:18 volumio volumio[1045]: info: ------------------------------ 9ms Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces state update: player Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces state update: player Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:19 volumio volumio[1045]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 04 13:57:19 volumio volumio[1045]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 04 13:57:19 volumio volumio[1045]: info: camilladsp stopping service pid 6820... Jul 04 13:57:19 volumio volumio[1045]: info: camilladsp service terminated, instance 1 Jul 04 13:57:19 volumio volumio[1045]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 04 13:57:19 volumio volumio[1045]: info: camilladsp service started and running in background, instance 1 Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:19 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces state update: player Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand status took 117 milliseconds Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand status took 113 milliseconds Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces system playlist update Jul 04 13:57:19 volumio volumio[1045]: info: Ignoring MPD Status Update Jul 04 13:57:19 volumio volumio[1045]: info: Jul 04 13:57:19 volumio volumio[1045]: ---------------------------- MPD announces state update: player Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 12ms Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand status took 9 milliseconds Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 6ms Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand status took 4 milliseconds Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:19 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus stop Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:19 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus play Jul 04 13:57:19 volumio volumio[1045]: info: Received an update from plugin. extracting info from payload Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 154ms Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 163ms Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 04 13:57:19 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:19 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:19 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus play Jul 04 13:57:19 volumio volumio[1045]: info: Received an update from plugin. extracting info from payload Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:19 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus play Jul 04 13:57:19 volumio volumio[1045]: info: Received an update from plugin. extracting info from payload Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:19 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 141ms Jul 04 13:57:19 volumio volumio[1045]: info: ------------------------------ 134ms Jul 04 13:57:32 volumio nmbd[969]: [2025/07/04 13:57:32.498103, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 04 13:57:32 volumio nmbd[969]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jul 04 13:57:36 volumio nmbd[969]: [2025/07/04 13:57:36.523823, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 04 13:57:36 volumio nmbd[969]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jul 04 13:57:37 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:37 volumio volumio[1045]: info: CURURI: music-library Jul 04 13:57:37 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:38 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:38 volumio volumio[1045]: info: CURURI: music-library/NAS Jul 04 13:57:38 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:39 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:39 volumio volumio[1045]: info: CURURI: music-library/NAS/Media_3G Jul 04 13:57:39 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:40 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 04 13:57:40 volumio volumio[1045]: info: CURURI: music-library/NAS/Media_3G/Maryia Jul 04 13:57:40 volumio volumio[1045]: info: Preload queue cleared Jul 04 13:57:44 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: info: Retrieving Cloud Streaming UI Jul 04 13:57:46 volumio volumio[1045]: info: Getting Tidal Cloud Configuration Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: info: Getting Qobuz Cloud Configuration Jul 04 13:57:46 volumio volumio[1045]: info: Asking plugin for UI Config Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: info: Getting Spotify Cloud Configuration Jul 04 13:57:46 volumio volumio[1045]: info: Asking plugin for UI Config Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: info: Saving Spotify Acccount Jul 04 13:57:46 volumio volumio[1045]: info: Got Tidal Cloud Configuration Jul 04 13:57:46 volumio volumio[1045]: info: Got it Jul 04 13:57:46 volumio volumio[1045]: info: Got it Jul 04 13:57:46 volumio volumio[1045]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 13:57:46 volumio volumio[1045]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 13:57:46 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand rescan Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:48 volumio volumio[1045]: info: Jul 04 13:57:48 volumio volumio[1045]: ---------------------------- MPD announces state update: update Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand rescan took 12 milliseconds Jul 04 13:57:48 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:48 volumio volumio[1045]: info: Jul 04 13:57:48 volumio volumio[1045]: ---------------------------- MPD announces state update: update Jul 04 13:57:48 volumio volumio[1045]: info: ControllerMpd::getState Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand status Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand status took 13 milliseconds Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand status took 10 milliseconds Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand status took 12 milliseconds Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand status took 10 milliseconds Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:48 volumio volumio[1045]: info: Command Router : Notfying DB Updatetrue Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:48 volumio volumio[1045]: info: Command Router : Notfying DB Updatetrue Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseState Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:48 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:48 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:48 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24071,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:48 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus play Jul 04 13:57:48 volumio volumio[1045]: info: Received an update from plugin. extracting info from payload Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:48 volumio volumio[1045]: info: ------------------------------ 132ms Jul 04 13:57:48 volumio volumio[1045]: info: sendMpdCommand playlistinfo took 59 milliseconds Jul 04 13:57:48 volumio volumio[1045]: verbose: ControllerMpd::parseTrackInfo Jul 04 13:57:48 volumio volumio[1045]: info: ControllerMpd::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::servicePushState Jul 04 13:57:48 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:48 volumio volumio[1045]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24071,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"avtoradio","artist":"AVTORADIO BELARUS","album":null,"uri":"http://live.avtoradio.by:8000/avtoradio","trackType":""} Jul 04 13:57:48 volumio volumio[1045]: verbose: CURRENT POSITION 0 Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::syncState stateService play Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::syncState currentStatus play Jul 04 13:57:48 volumio volumio[1045]: info: Received an update from plugin. extracting info from payload Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 04 13:57:48 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 04 13:57:48 volumio volumio[1045]: info: ------------------------------ 166ms Jul 04 13:57:50 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 04 13:57:50 volumio volumio[1045]: verbose: ControllerMpd::sendMpdCommand update Jul 04 13:57:50 volumio volumio[1045]: info: sendMpdCommand update took 8 milliseconds Jul 04 13:57:50 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 13:57:53 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 04 13:57:53 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 04 13:57:54 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 13:57:58 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 13:57:58 volumio volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 13:57:58 volumio volumio[1045]: Error: dns service error: unknown Jul 04 13:57:58 volumio volumio[1045]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 04 13:57:58 volumio volumio[1045]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 04 13:57:58 volumio volumio[1045]: errorCode: -65537 Jul 04 13:57:58 volumio volumio[1045]: } Jul 04 13:57:58 volumio volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 13:57:59 volumio sudo[7073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 13:56 Jul 04 13:57:59 volumio sudo[7073]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"