-- Logs begin at Wed 2024-04-17 14:44:14 +07, end at Wed 2024-04-17 15:03:01 +07. -- Apr 17 15:02:03 volumio volumio[742]: info: Get:33 http://deb.debian.org/debian buster/main amd64 libpython3-dev amd64 3.7.3-1 [20.1 kB] Apr 17 15:02:03 volumio volumio[742]: info: Get:34 http://deb.debian.org/debian buster/main amd64 python-pip-whl all 18.1-5 [1591 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:35 http://deb.debian.org/debian buster/main amd64 python3-multidict amd64 4.5.2-1 [60.6 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:36 http://deb.debian.org/debian buster/main amd64 python3-yarl amd64 1.3.0-1 [48.3 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:37 http://deb.debian.org/debian buster/main amd64 python3-async-timeout all 3.0.1-1 [6292 B] Apr 17 15:02:05 volumio volumio[742]: info: Get:38 http://deb.debian.org/debian buster/main amd64 python3-attr all 18.2.0-1 [37.3 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:39 http://deb.debian.org/debian buster/main amd64 python3-aiohttp amd64 3.5.1-1+deb10u1 [261 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:40 http://deb.debian.org/debian buster/main amd64 python3-asn1crypto all 0.24.0-1 [78.2 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:41 http://deb.debian.org/debian buster/main amd64 python3-cffi-backend amd64 1.12.2-1 [79.7 kB] Apr 17 15:02:05 volumio volumio[742]: info: Get:42 http://deb.debian.org/debian buster/main amd64 python3-crypto amd64 2.6.1-9+b1 [263 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:43 http://deb.debian.org/debian buster/main amd64 python3-cryptography amd64 2.6.1-3+deb10u2 [219 kB] Apr 17 15:02:06 volumio sudo[3726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 15:02:06 volumio sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:06 volumio sudo[3726]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:06 volumio sudo[3729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 15:02:06 volumio sudo[3729]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:06 volumio sudo[3729]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:06 volumio volumio[742]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.74 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 10 Apr 17 15:02:06 volumio volumio[742]: info: Get:44 http://deb.debian.org/debian buster/main amd64 python3-dbus amd64 1.2.8-3 [103 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:45 http://deb.debian.org/debian buster/main amd64 python3.7-dev amd64 3.7.3-2+deb10u3 [510 kB] Apr 17 15:02:06 volumio ntpd[2659]: Soliciting pool server 2401:c080:3000:2945:5400:4ff:fe69:f923 Apr 17 15:02:06 volumio volumio[742]: info: Get:46 http://deb.debian.org/debian buster/main amd64 python3-dev amd64 3.7.3-1 [1264 B] Apr 17 15:02:06 volumio volumio[742]: info: Get:47 http://deb.debian.org/debian buster/main amd64 python3-entrypoints all 0.3-1 [5508 B] Apr 17 15:02:06 volumio volumio[742]: info: Get:48 http://deb.debian.org/debian buster/main amd64 python3-gi amd64 3.30.4-1 [180 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:49 http://deb.debian.org/debian buster/main amd64 python3-secretstorage all 2.3.1-2 [14.2 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:50 http://deb.debian.org/debian buster/main amd64 python3-keyring all 17.1.1-1 [43.1 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:51 http://deb.debian.org/debian buster/main amd64 python3-keyrings.alt all 3.1.1-1 [18.2 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:52 http://deb.debian.org/debian buster/main amd64 python3-pip all 18.1-5 [171 kB] Apr 17 15:02:06 volumio volumio[742]: info: Get:53 http://deb.debian.org/debian buster/main amd64 python3-setuptools all 40.8.0-1 [306 kB] Apr 17 15:02:07 volumio volumio[742]: info: Get:54 http://deb.debian.org/debian buster/main amd64 python3-wheel all 0.32.3-2 [19.4 kB] Apr 17 15:02:07 volumio volumio[742]: info: Get:55 http://deb.debian.org/debian buster/main amd64 python3-xdg all 0.25-5 [35.9 kB] Apr 17 15:02:07 volumio volumio[742]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.74 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 15:02:07 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:07 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: Listing playlists Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 15:02:07 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:07 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:07 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:07 volumio volumio[742]: info: Listing playlists Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 17 15:02:07 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 17 15:02:07 volumio volumio[742]: info: Fetched 58.7 MB in 2min 59s (328 kB/s) Apr 17 15:02:07 volumio volumio[742]: info: Selecting previously unselected package bzip2. Apr 17 15:02:08 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 17 15:02:08 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:08 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:08 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:08 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:08 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:08 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:08 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:08 volumio volumio[742]: [620B blob data] Apr 17 15:02:08 volumio volumio[742]: info: Preparing to unpack .../00-bzip2_1.0.6-9.2~deb10u1_amd64.deb ... Apr 17 15:02:08 volumio volumio[742]: info: Unpacking bzip2 (1.0.6-9.2~deb10u1) ... Apr 17 15:02:09 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 17 15:02:09 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:09 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:09 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:09 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:09 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:09 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:09 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:11 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 17 15:02:11 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 17 15:02:22 volumio sudo[3803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 15:02:22 volumio sudo[3803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:22 volumio sudo[3803]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:22 volumio sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 15:02:22 volumio sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:22 volumio sudo[3806]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:22 volumio volumio[742]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.239 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Total Clients: 11 Apr 17 15:02:23 volumio sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 17 15:02:23 volumio sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:23 volumio sudo[3809]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:23 volumio sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 15:02:23 volumio sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:23 volumio sudo[3812]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:23 volumio volumio[742]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.239 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Total Clients: 12 Apr 17 15:02:23 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:24 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 15:02:24 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:24 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:24 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:24 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:24 volumio volumio[742]: info: Listing playlists Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 17 15:02:24 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:24 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:25 volumio volumio[742]: info: Selecting previously unselected package binutils-common:amd64. Apr 17 15:02:25 volumio volumio[742]: info: Preparing to unpack .../01-binutils-common_2.31.1-16_amd64.deb ... Apr 17 15:02:25 volumio volumio[742]: info: Unpacking binutils-common:amd64 (2.31.1-16) ... Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 17 15:02:25 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:25 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:25 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 17 15:02:25 volumio volumio[742]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 17 15:02:25 volumio volumio[742]: info: Received Get System Version Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 15:02:25 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:25 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:25 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:25 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:26 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 17 15:02:26 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:26 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:26 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:26 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:26 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:26 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:26 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:27 volumio volumio[742]: info: Selecting previously unselected package libbinutils:amd64. Apr 17 15:02:27 volumio volumio[742]: info: Preparing to unpack .../02-libbinutils_2.31.1-16_amd64.deb ... Apr 17 15:02:27 volumio volumio[742]: info: Unpacking libbinutils:amd64 (2.31.1-16) ... Apr 17 15:02:28 volumio volumio[742]: info: Selecting previously unselected package binutils-x86-64-linux-gnu. Apr 17 15:02:28 volumio volumio[742]: info: Preparing to unpack .../03-binutils-x86-64-linux-gnu_2.31.1-16_amd64.deb ... Apr 17 15:02:28 volumio volumio[742]: info: Unpacking binutils-x86-64-linux-gnu (2.31.1-16) ... Apr 17 15:02:36 volumio volumio[742]: info: Enabling plugin spop Apr 17 15:02:36 volumio volumio[742]: info: Loading plugin "spop"... Apr 17 15:02:44 volumio volumio[742]: info: PLUGIN START: spop Apr 17 15:02:45 volumio volumio[742]: info: Creating Spotify config file Apr 17 15:02:45 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:45 volumio volumio[742]: info: Done. Apr 17 15:02:45 volumio volumio[742]: info: Spotify config file written Apr 17 15:02:45 volumio volumio[742]: info: Selecting previously unselected package binutils. Apr 17 15:02:45 volumio sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 17 15:02:45 volumio sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:45 volumio volumio[742]: info: Preparing to unpack .../04-binutils_2.31.1-16_amd64.deb ... Apr 17 15:02:45 volumio volumio[742]: info: Unpacking binutils (2.31.1-16) ... Apr 17 15:02:45 volumio volumio[742]: info: Selecting previously unselected package libcc1-0:amd64. Apr 17 15:02:45 volumio volumio[742]: info: Preparing to unpack .../05-libcc1-0_8.3.0-6_amd64.deb ... Apr 17 15:02:45 volumio volumio[742]: info: Unpacking libcc1-0:amd64 (8.3.0-6) ... Apr 17 15:02:45 volumio volumio[742]: info: Selecting previously unselected package libitm1:amd64. Apr 17 15:02:45 volumio volumio[742]: info: Preparing to unpack .../06-libitm1_8.3.0-6_amd64.deb ... Apr 17 15:02:45 volumio volumio[742]: info: Unpacking libitm1:amd64 (8.3.0-6) ... Apr 17 15:02:45 volumio volumio[742]: info: Enabling plugin youtube2 Apr 17 15:02:45 volumio volumio[742]: info: Loading plugin "youtube2"... Apr 17 15:02:45 volumio volumio[742]: info: PLUGIN START: youtube2 Apr 17 15:02:45 volumio volumio[742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 15:02:45 volumio volumio[742]: info: [1713340965576] CoreMusicLibrary::Adding element YouTube2 Apr 17 15:02:45 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 15:02:45 volumio volumio[742]: Cannot find translation for source YouTube2 Apr 17 15:02:45 volumio volumio[742]: info: Done. Apr 17 15:02:45 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 17 15:02:45 volumio volumio[742]: info: Enabling plugin fusiondsp Apr 17 15:02:45 volumio volumio[742]: info: Loading plugin "fusiondsp"... Apr 17 15:02:45 volumio systemd[1]: Started go-librespot Daemon. Apr 17 15:02:45 volumio sudo[3876]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:45 volumio go-librespot[3878]: Librespot-go daemon starting... Apr 17 15:02:49 volumio go-librespot[3878]: time="2024-04-17T15:02:49+07:00" level=info msg="generated new device id: 3b70f29939bb4ebc3b2ab946145175100d11bc23" Apr 17 15:02:51 volumio go-librespot[3878]: time="2024-04-17T15:02:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 17 15:02:51 volumio go-librespot[3878]: time="2024-04-17T15:02:51+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 17 15:02:51 volumio go-librespot[3878]: time="2024-04-17T15:02:51+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 17 15:02:51 volumio go-librespot[3878]: time="2024-04-17T15:02:51+07:00" level=debug msg="zeroconf server listening on port 41135" Apr 17 15:02:57 volumio volumio[742]: info: Preparing to generate the ALSA configuration file Apr 17 15:02:57 volumio volumio[742]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 17 15:02:57 volumio volumio[742]: info: Selecting previously unselected package libasan5:amd64. Apr 17 15:02:57 volumio volumio[742]: info: Preparing to unpack .../07-libasan5_8.3.0-6_amd64.deb ... Apr 17 15:02:57 volumio volumio[742]: info: Received Get System Version Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 15:02:57 volumio volumio[742]: info: Unpacking libasan5:amd64 (8.3.0-6) ... Apr 17 15:02:57 volumio volumio[742]: info: Selecting previously unselected package liblsan0:amd64. Apr 17 15:02:57 volumio volumio[742]: info: Preparing to unpack .../08-liblsan0_8.3.0-6_amd64.deb ... Apr 17 15:02:57 volumio volumio[742]: info: Unpacking liblsan0:amd64 (8.3.0-6) ... Apr 17 15:02:57 volumio volumio[742]: info: Selecting previously unselected package libtsan0:amd64. Apr 17 15:02:57 volumio volumio[742]: info: Preparing to unpack .../09-libtsan0_8.3.0-6_amd64.deb ... Apr 17 15:02:57 volumio volumio[742]: info: Unpacking libtsan0:amd64 (8.3.0-6) ... Apr 17 15:02:57 volumio volumio[742]: info: Selecting previously unselected package libubsan1:amd64. Apr 17 15:02:57 volumio volumio[742]: info: Preparing to unpack .../10-libubsan1_8.3.0-6_amd64.deb ... Apr 17 15:02:57 volumio volumio[742]: info: Unpacking libubsan1:amd64 (8.3.0-6) ... Apr 17 15:02:57 volumio volumio[742]: info: Selecting previously unselected package libmpx2:amd64. Apr 17 15:02:57 volumio volumio[742]: info: Preparing to unpack .../11-libmpx2_8.3.0-6_amd64.deb ... Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:57 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:57 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:57 volumio volumio[742]: info: Asound.conf file unchanged, so no further update is needed Apr 17 15:02:57 volumio volumio[742]: info: Output device has changed, restarting MPD Apr 17 15:02:57 volumio sudo[3924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 15:02:57 volumio sudo[3924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:57 volumio volumio[742]: info: Output device has changed, restarting Shairport Sync Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:57 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 15:02:57 volumio sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 15:02:57 volumio sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:57 volumio systemd[1]: Stopping Music Player Daemon... Apr 17 15:02:58 volumio sudo[3924]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:58 volumio volumio[742]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 15:02:58 volumio volumio[742]: info: PLUGIN START: fusiondsp Apr 17 15:02:58 volumio volumio[742]: info: Loading i18n strings for locale vi Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: FusionDsp - mixtype--------------------- Hardware Apr 17 15:02:58 volumio volumio[742]: info: Preparing to generate the ALSA configuration file Apr 17 15:02:58 volumio volumio[742]: info: Done. Apr 17 15:02:58 volumio volumio[742]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 17 15:02:58 volumio volumio[742]: info: Reading ALSA contributions from plugins. Apr 17 15:02:58 volumio volumio[742]: info: MPD Permissions set Apr 17 15:02:58 volumio volumio[742]: info: Received Get System Info Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 17 15:02:58 volumio volumio[742]: info: Discovery: Getting this device information Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::volumioGetState Apr 17 15:02:58 volumio volumio[742]: info: CorePlayQueue::getTrack 0 Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:02:58 volumio volumio[742]: info: Unpacking libmpx2:amd64 (8.3.0-6) ... Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 15:02:58 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:58 volumio volumio[742]: info: Starting Shairport Sync Apr 17 15:02:58 volumio volumio[742]: info: Asound.conf file written Apr 17 15:02:58 volumio sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 17 15:02:58 volumio sudo[3944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 15:02:58 volumio sudo[3944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:58 volumio sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:58 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 15:02:58 volumio sudo[3946]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:59 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 15:02:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 15:02:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 15:02:59 volumio sudo[3944]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:59 volumio volumio[742]: info: Output device has changed, restarting MPD Apr 17 15:02:59 volumio volumio[742]: info: Output device has changed, restarting Shairport Sync Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 15:02:59 volumio sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 17 15:02:59 volumio sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 17 15:02:59 volumio sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:59 volumio sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:59 volumio sudo[3954]: pam_unix(sudo:session): session closed for user root Apr 17 15:02:59 volumio volumio[742]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 15:02:59 volumio volumio[742]: info: MPD Permissions set Apr 17 15:02:59 volumio volumio[742]: info: Shairport-Sync Started Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 17 15:02:59 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:02:59 volumio volumio[742]: info: Starting Shairport Sync Apr 17 15:02:59 volumio sudo[3976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 17 15:02:59 volumio sudo[3976]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:02:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 17 15:03:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 17 15:03:00 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 17 15:03:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 17 15:03:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 17 15:03:00 volumio sudo[3976]: pam_unix(sudo:session): session closed for user root Apr 17 15:03:00 volumio volumio[742]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 17 15:03:00 volumio volumio[742]: info: camilladsp service started and running in background, instance 1 Apr 17 15:03:00 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 15:03:00 volumio volumio[742]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 15:03:00 volumio volumio[742]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 17 15:03:00 volumio volumio[742]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 17 15:03:00 volumio volumio[742]: info: FusionDsp loaded Apr 17 15:03:00 volumio volumio[742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 17 15:03:00 volumio volumio[742]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 17 15:03:00 volumio volumio[742]: info: Adding Signal Path Element [object Object] Apr 17 15:03:00 volumio volumio[742]: info: Adding fusiondspeq DSP Signal Path Element Apr 17 15:03:00 volumio volumio[742]: info: FusionDsp - ---- installed callbackRead Apr 17 15:03:00 volumio volumio[742]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 15:03:00 volumio sudo[3989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 17 15:03:00 volumio sudo[3989]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 15:03:00 volumio volumio[742]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 17 15:03:00 volumio volumio[742]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 17 15:03:00 volumio volumio[742]: at onErrorNT (internal/child_process.js:472:16) Apr 17 15:03:00 volumio volumio[742]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 17 15:03:00 volumio volumio[742]: at runNextTicks (internal/process/task_queues.js:64:3) Apr 17 15:03:00 volumio volumio[742]: at listOnTimeout (internal/timers.js:526:9) Apr 17 15:03:00 volumio volumio[742]: at processTimers (internal/timers.js:500:7) { Apr 17 15:03:00 volumio volumio[742]: errno: -2, Apr 17 15:03:00 volumio volumio[742]: code: 'ENOENT', Apr 17 15:03:00 volumio volumio[742]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 15:03:00 volumio volumio[742]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 17 15:03:00 volumio volumio[742]: spawnargs: [ Apr 17 15:03:00 volumio volumio[742]: '-p', Apr 17 15:03:00 volumio volumio[742]: 9876, Apr 17 15:03:00 volumio volumio[742]: '-o', Apr 17 15:03:00 volumio volumio[742]: '/tmp/camilladsp.log', Apr 17 15:03:00 volumio volumio[742]: '-l', Apr 17 15:03:00 volumio volumio[742]: 'warn', Apr 17 15:03:00 volumio volumio[742]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 17 15:03:00 volumio volumio[742]: ] Apr 17 15:03:00 volumio volumio[742]: } Apr 17 15:03:00 volumio volumio[742]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 15:03:00 volumio sudo[3989]: pam_unix(sudo:session): session closed for user root Apr 17 15:03:01 volumio systemd[1]: mpd.service: Succeeded. Apr 17 15:03:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 17 15:03:01 volumio systemd[1]: Starting Music Player Daemon... Apr 17 15:03:01 volumio sudo[4006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-17 15:02 Apr 17 15:03:01 volumio sudo[4006]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"