-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Mon 2024-10-07 16:46:16 EEST. -- Oct 07 16:45:08 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:08 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:08 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:18 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: verbose: New Socket.io Connection to 192.168.175.140 from 192.168.175.237 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Oct 07 16:45:24 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 16:45:24 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:24 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 16:45:24 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:24 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:24 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 16:45:24 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 16:45:25 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:25 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:25 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:25 elutuba volumio[843]: info: Received Get System Info Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:25 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 07 16:45:25 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 07 16:45:28 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:28 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:28 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:31 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 07 16:45:37 elutuba volumio[843]: info: Get:36 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:37 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-multidict armhf 4.5.2-1 [52.9 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:38 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-yarl armhf 1.3.0-1 [44.0 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:39 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-async-timeout all 3.0.1-1 [6292 B] Oct 07 16:45:37 elutuba volumio[843]: info: Get:40 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-attr all 18.2.0-1 [37.3 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:41 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-aiohttp armhf 3.5.1-1+deb10u1 [241 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:42 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-asn1crypto all 0.24.0-1 [78.2 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:43 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-cffi-backend armhf 1.12.2-1 [70.1 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:44 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-crypto armhf 2.6.1-9+b1 [251 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:45 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-cryptography armhf 2.6.1-3+deb10u4 [184 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:46 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-dbus armhf 1.2.8-3 [95.9 kB] Oct 07 16:45:37 elutuba volumio[843]: info: Get:47 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3.7-dev armhf 3.7.3-2+deb10u7 [527 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:49 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-entrypoints all 0.3-1 [5508 B] Oct 07 16:45:38 elutuba volumio[843]: info: Get:50 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-gi armhf 3.30.4-1 [161 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:51 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-secretstorage all 2.3.1-2 [14.2 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:52 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-keyring all 17.1.1-1 [43.1 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:53 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-keyrings.alt all 3.1.1-1 [18.2 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:54 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-setuptools all 40.8.0-1 [306 kB] Oct 07 16:45:38 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:38 elutuba volumio[843]: info: Get:55 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-wheel all 0.32.3-2 [19.4 kB] Oct 07 16:45:38 elutuba volumio[843]: info: Get:56 http://mirror.litnet.lt/raspbian/raspbian buster/main armhf python3-xdg all 0.25-5 [35.9 kB] Oct 07 16:45:40 elutuba volumio[843]: info: Fetched 84.3 MB in 1min 16s (1115 kB/s) Oct 07 16:45:42 elutuba volumio[843]: [620B blob data] Oct 07 16:45:42 elutuba volumio[843]: info: Preparing to unpack .../00-python3.7_3.7.3-2+deb10u7_armhf.deb ... Oct 07 16:45:42 elutuba volumio[843]: info: Unpacking python3.7 (3.7.3-2+deb10u7) over (3.7.3-2+deb10u6) ... Oct 07 16:45:45 elutuba volumio[843]: info: Preparing to unpack .../01-libpython3.7-stdlib_3.7.3-2+deb10u7_armhf.deb ... Oct 07 16:45:45 elutuba volumio[843]: info: Unpacking libpython3.7-stdlib:armhf (3.7.3-2+deb10u7) over (3.7.3-2+deb10u6) ... Oct 07 16:45:48 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:48 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:48 elutuba volumio[843]: info: Listing playlists Oct 07 16:45:52 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 16:45:52 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 16:45:52 elutuba volumio[843]: info: Discovery: Getting this device information Oct 07 16:45:52 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:52 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:45:56 elutuba volumio[843]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/onkyo_control/volumio/buster/armhf Oct 07 16:45:57 elutuba volumio[843]: info: Preparing to unpack .../02-python3.7-minimal_3.7.3-2+deb10u7_armhf.deb ... Oct 07 16:45:57 elutuba volumio[843]: info: Unpacking python3.7-minimal (3.7.3-2+deb10u7) over (3.7.3-2+deb10u6) ... Oct 07 16:45:58 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:45:59 elutuba volumio[843]: info: Preparing to unpack .../03-libpython3.7-minimal_3.7.3-2+deb10u7_armhf.deb ... Oct 07 16:45:59 elutuba volumio[843]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/onkyo_control/volumio/buster/armhf Oct 07 16:45:59 elutuba volumio[843]: info: Folder /tmp/plugins removed Oct 07 16:45:59 elutuba volumio[843]: info: Unpacking libpython3.7-minimal:armhf (3.7.3-2+deb10u7) over (3.7.3-2+deb10u6) ... Oct 07 16:46:00 elutuba volumio[843]: info: Check plugin dependencies Oct 07 16:46:00 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 07 16:46:00 elutuba volumio[843]: info: Checking if plugin already exists Oct 07 16:46:00 elutuba volumio[843]: info: Rename folder Oct 07 16:46:00 elutuba volumio[843]: info: Folder /tmp/downloaded_plugin.zip removed Oct 07 16:46:00 elutuba volumio[843]: info: Move to category Oct 07 16:46:01 elutuba volumio[843]: info: Checking if install.sh is present Oct 07 16:46:01 elutuba volumio[843]: info: Executing install.sh Oct 07 16:46:01 elutuba sudo[7635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/onkyo_control/install.sh Oct 07 16:46:01 elutuba sudo[7635]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:01 elutuba volumio[843]: info: Installing onkyo control Dependencies Oct 07 16:46:01 elutuba sudo[7635]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:01 elutuba volumio[843]: info: Installing onkyo control Dependencies Oct 07 16:46:01 elutuba volumio[843]: info: Install script completed Oct 07 16:46:01 elutuba volumio[843]: info: Adding reference to registry Oct 07 16:46:01 elutuba volumio[843]: info: Done installing plugin. Oct 07 16:46:01 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 07 16:46:01 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 07 16:46:01 elutuba volumio[843]: Plugin install end detected on script Oct 07 16:46:01 elutuba volumio[843]: Plugin install end detected on script Oct 07 16:46:01 elutuba volumio[843]: info: Folder /tmp/plugins removed Oct 07 16:46:01 elutuba volumio[843]: info: Folder /tmp/downloaded_plugin.zip removed Oct 07 16:46:01 elutuba volumio[843]: info: Folder /data/temp removed Oct 07 16:46:04 elutuba volumio[843]: info: Enabling plugin onkyo_control Oct 07 16:46:04 elutuba volumio[843]: info: Loading plugin "onkyo_control"... Oct 07 16:46:05 elutuba volumio[843]: info: PLUGIN START: onkyo_control Oct 07 16:46:05 elutuba volumio[843]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 07 16:46:05 elutuba volumio[843]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STARTED ******** Oct 07 16:46:05 elutuba volumio[843]: info: Done. Oct 07 16:46:05 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:46:05 elutuba volumio[843]: info: ONKYO-CONTROL: New state: {"status":"play","title":"Rhythm","artist":"Tantrum Desire","album":"Rhythm - Single","albumart":"/albumart?cacheid=422&web=Tantrum%20Desire/Rhythm%20-%20Single/extralarge&path=&metadata=false","uri":"","trackType":"airplay","codec":"","seek":233000,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"consume":false,"volume":99,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"disableUiControls":true,"service":"airplay_emulation"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.175.8","model":"TX-NR636"} Oct 07 16:46:05 elutuba volumio[843]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (server_error) Server error on 192.168.175.8:60128 - Error: connect EALREADY 192.168.175.8:60128 - Local (192.168.175.140:38224) Oct 07 16:46:05 elutuba volumio[843]: at internalConnect (net.js:921:16) Oct 07 16:46:05 elutuba volumio[843]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 07 16:46:05 elutuba volumio[843]: at net.js:1009:9 Oct 07 16:46:05 elutuba volumio[843]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 07 16:46:05 elutuba volumio[843]: errno: -114, Oct 07 16:46:05 elutuba volumio[843]: code: 'EALREADY', Oct 07 16:46:05 elutuba volumio[843]: syscall: 'connect', Oct 07 16:46:05 elutuba volumio[843]: address: '192.168.175.8', Oct 07 16:46:05 elutuba volumio[843]: port: 60128 Oct 07 16:46:05 elutuba volumio[843]: } Oct 07 16:46:08 elutuba volumio[843]: info: CoreCommandRouter::volumioGetState Oct 07 16:46:08 elutuba volumio[843]: info: Listing playlists Oct 07 16:46:08 elutuba volumio[843]: info: Listing playlists Oct 07 16:46:09 elutuba volumio[843]: info: Enabling plugin fusiondsp Oct 07 16:46:09 elutuba volumio[843]: info: Loading plugin "fusiondsp"... Oct 07 16:46:10 elutuba volumio[843]: info: Preparing to generate the ALSA configuration file Oct 07 16:46:10 elutuba volumio[843]: info: Asound.conf file unchanged, so no further update is needed Oct 07 16:46:10 elutuba volumio[843]: info: Output device has changed, restarting MPD Oct 07 16:46:10 elutuba volumio[843]: info: Output device has changed, restarting Shairport Sync Oct 07 16:46:10 elutuba sudo[7694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 16:46:10 elutuba sudo[7694]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 16:46:10 elutuba sudo[7694]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:10 elutuba sudo[7696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 16:46:10 elutuba sudo[7696]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:10 elutuba systemd[1]: Stopping Music Player Daemon... Oct 07 16:46:10 elutuba volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 16:46:10 elutuba volumio[843]: info: PLUGIN START: fusiondsp Oct 07 16:46:10 elutuba volumio[843]: info: Loading i18n strings for locale en Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: FusionDsp - mixtype--------------------- Hardware Oct 07 16:46:10 elutuba volumio[843]: info: Preparing to generate the ALSA configuration file Oct 07 16:46:10 elutuba volumio[843]: info: Done. Oct 07 16:46:10 elutuba volumio[843]: info: MPD Permissions set Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:10 elutuba volumio[843]: info: Asound.conf file unchanged, so no further update is needed Oct 07 16:46:10 elutuba volumio[843]: info: Output device has changed, restarting MPD Oct 07 16:46:10 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 16:46:10 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 16:46:11 elutuba sudo[7708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 07 16:46:11 elutuba sudo[7708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:11 elutuba volumio[843]: info: Output device has changed, restarting Shairport Sync Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 16:46:11 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 16:46:11 elutuba sudo[7708]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:11 elutuba sudo[7710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 07 16:46:11 elutuba sudo[7710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:11 elutuba volumio[843]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 07 16:46:11 elutuba systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 07 16:46:11 elutuba systemd[1]: mpd.service: Succeeded. Oct 07 16:46:11 elutuba systemd[1]: Stopped Music Player Daemon. Oct 07 16:46:11 elutuba systemd[1]: Starting Music Player Daemon... Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 16:46:11 elutuba volumio[843]: info: MPD Permissions set Oct 07 16:46:11 elutuba volumio[843]: info: Starting Shairport Sync Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba sudo[7721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 07 16:46:11 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:11 elutuba sudo[7721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:11 elutuba systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 07 16:46:11 elutuba shairport-sync[1253]: {"time":1728308272460,"response":"startAirplayPlayback Success"} Oct 07 16:46:11 elutuba systemd[1]: shairport-sync.service: Succeeded. Oct 07 16:46:11 elutuba systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 07 16:46:11 elutuba systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 07 16:46:11 elutuba sudo[7721]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:14 elutuba mpd[7719]: Oct 07 16:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 07 16:46:14 elutuba systemd[1]: Started Music Player Daemon. Oct 07 16:46:14 elutuba sudo[7710]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:14 elutuba sudo[7696]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:15 elutuba volumio[843]: error: ONKYO-CONTROL: An error occurred trying to comminicate with the receiver: ERROR (cmd_not_exist) Command power does not exist in zone main Oct 07 16:46:15 elutuba volumio[843]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 07 16:46:15 elutuba volumio[843]: info: camilladsp service started and running in background, instance 1 Oct 07 16:46:15 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 07 16:46:15 elutuba volumio[843]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 16:46:15 elutuba volumio[843]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 07 16:46:15 elutuba volumio[843]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 07 16:46:15 elutuba volumio[843]: info: FusionDsp loaded Oct 07 16:46:15 elutuba volumio[843]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 16:46:15 elutuba sudo[7788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 07 16:46:15 elutuba sudo[7788]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 16:46:15 elutuba volumio[843]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 07 16:46:15 elutuba volumio[843]: info: Adding Signal Path Element [object Object] Oct 07 16:46:15 elutuba volumio[843]: info: Adding fusiondspeq DSP Signal Path Element Oct 07 16:46:15 elutuba volumio[843]: info: FusionDsp - ---- installed callbackRead Oct 07 16:46:15 elutuba volumio[843]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 16:46:15 elutuba sudo[7788]: pam_unix(sudo:session): session closed for user root Oct 07 16:46:15 elutuba volumio[843]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 07 16:46:15 elutuba volumio[843]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 07 16:46:15 elutuba volumio[843]: at onErrorNT (internal/child_process.js:465:16) Oct 07 16:46:15 elutuba volumio[843]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 16:46:15 elutuba volumio[843]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 07 16:46:15 elutuba volumio[843]: at listOnTimeout (internal/timers.js:523:9) Oct 07 16:46:15 elutuba volumio[843]: at processTimers (internal/timers.js:497:7) { Oct 07 16:46:15 elutuba volumio[843]: errno: -2, Oct 07 16:46:15 elutuba volumio[843]: code: 'ENOENT', Oct 07 16:46:15 elutuba volumio[843]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 16:46:15 elutuba volumio[843]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 07 16:46:15 elutuba volumio[843]: spawnargs: [ Oct 07 16:46:15 elutuba volumio[843]: '-p', Oct 07 16:46:15 elutuba volumio[843]: 9876, Oct 07 16:46:15 elutuba volumio[843]: '-o', Oct 07 16:46:15 elutuba volumio[843]: '/tmp/camilladsp.log', Oct 07 16:46:15 elutuba volumio[843]: '-l', Oct 07 16:46:15 elutuba volumio[843]: 'warn', Oct 07 16:46:15 elutuba volumio[843]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 07 16:46:15 elutuba volumio[843]: ] Oct 07 16:46:15 elutuba volumio[843]: } Oct 07 16:46:15 elutuba volumio[843]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 16:46:16 elutuba sudo[7800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 16:45 Oct 07 16:46:16 elutuba sudo[7800]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"