-- Logs begin at Mon 2024-10-21 15:29:38 CEST, end at Mon 2024-10-21 19:37:53 CEST. -- Oct 21 19:36:11 dellvol go-librespot[1959]: time="2024-10-21T19:36:11+02:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:36:15 dellvol sudo[24798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 21 19:36:15 dellvol sudo[24798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 19:36:15 dellvol sudo[24798]: pam_unix(sudo:session): session closed for user root Oct 21 19:36:15 dellvol sudo[24801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 21 19:36:15 dellvol sudo[24801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 19:36:15 dellvol sudo[24801]: pam_unix(sudo:session): session closed for user root Oct 21 19:36:15 dellvol volumio[1132]: verbose: New Socket.io Connection to 10.0.4.55 from 10.0.4.119 UA: Mozilla/5.0 (Linux; Android 14; 2211133G Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 21 19:36:15 dellvol sudo[24804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 21 19:36:15 dellvol sudo[24804]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 19:36:15 dellvol sudo[24804]: pam_unix(sudo:session): session closed for user root Oct 21 19:36:15 dellvol sudo[24807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 21 19:36:15 dellvol sudo[24807]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 19:36:15 dellvol sudo[24807]: pam_unix(sudo:session): session closed for user root Oct 21 19:36:15 dellvol volumio[1132]: verbose: New Socket.io Connection to 10.0.4.55 from 10.0.4.119 UA: Mozilla/5.0 (Linux; Android 14; 2211133G Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetVisibleSources Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 21 19:36:15 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:15 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:15 dellvol volumio[1132]: info: Listing playlists Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 21 19:36:15 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 21 19:36:16 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 21 19:36:17 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:17 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:17 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:18 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 21 19:36:18 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:36:18 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:18 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:18 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:18 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:18 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:21 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetQueue Oct 21 19:36:21 dellvol volumio[1132]: info: CoreStateMachine::getQueue Oct 21 19:36:21 dellvol volumio[1132]: info: CorePlayQueue::getQueue Oct 21 19:36:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:24 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:24 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:24 dellvol go-librespot[1959]: time="2024-10-21T19:36:24+02:00" level=debug msg="fetched chunk 12/16, size: 524288" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:36:25 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 21 19:36:27 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 21 19:36:32 dellvol ntpd[24676]: ntpd exiting on signal 15 (Terminated) Oct 21 19:36:32 dellvol ntpd[24676]: 82.141.152.3 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol systemd[1]: Stopping Network Time Service... Oct 21 19:36:32 dellvol ntpd[24676]: 62.112.195.56 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 89.107.253.4 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 81.0.124.200 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 80.249.164.125 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 193.227.197.2 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 213.157.100.66 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 80.249.164.129 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol ntpd[24676]: 193.224.163.77 local addr 10.0.4.55 -> Oct 21 19:36:32 dellvol systemd[1]: ntp.service: Succeeded. Oct 21 19:36:32 dellvol systemd[1]: Stopped Network Time Service. Oct 21 19:36:32 dellvol systemd[1]: Starting Network Time Service... Oct 21 19:36:32 dellvol ntpd[24842]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 21 19:36:32 dellvol ntpd[24842]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 21 19:36:32 dellvol systemd[1]: Started Network Time Service. Oct 21 19:36:32 dellvol ntpd[24848]: proto: precision = 0.086 usec (-23) Oct 21 19:36:32 dellvol ntpd[24848]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 21 19:36:32 dellvol ntpd[24848]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 21 19:36:32 dellvol ntpd[24848]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 664 days ago Oct 21 19:36:32 dellvol ntpd[24848]: Listen and drop on 0 v6wildcard [::]:123 Oct 21 19:36:32 dellvol ntpd[24848]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 21 19:36:32 dellvol ntpd[24848]: Listen normally on 2 lo 127.0.0.1:123 Oct 21 19:36:32 dellvol ntpd[24848]: Listen normally on 3 eth0 10.0.4.55:123 Oct 21 19:36:32 dellvol ntpd[24848]: Listen normally on 4 wlan0 10.0.4.67:123 Oct 21 19:36:32 dellvol ntpd[24848]: Listening on routing socket on fd #21 for interface updates Oct 21 19:36:32 dellvol ntpd[24848]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 21 19:36:32 dellvol ntpd[24848]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 21 19:36:33 dellvol volumio[1132]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 21 19:36:33 dellvol volumio[1132]: info: Received Get System Version Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 19:36:33 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:33 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:33 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:34 dellvol ntpd[24848]: Soliciting pool server 89.107.253.4 Oct 21 19:36:35 dellvol ntpd[24848]: Soliciting pool server 62.112.195.56 Oct 21 19:36:36 dellvol ntpd[24848]: Soliciting pool server 193.224.163.77 Oct 21 19:36:36 dellvol ntpd[24848]: Soliciting pool server 81.0.124.200 Oct 21 19:36:36 dellvol ntpd[24848]: Soliciting pool server 81.0.124.253 Oct 21 19:36:37 dellvol ntpd[24848]: Soliciting pool server 62.112.195.26 Oct 21 19:36:37 dellvol go-librespot[1959]: time="2024-10-21T19:36:37+02:00" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:36:37 dellvol ntpd[24848]: Soliciting pool server 193.227.197.2 Oct 21 19:36:37 dellvol ntpd[24848]: Soliciting pool server 195.111.92.55 Oct 21 19:36:37 dellvol ntpd[24848]: Soliciting pool server 82.141.152.3 Oct 21 19:36:38 dellvol ntpd[24848]: Soliciting pool server 92.249.148.253 Oct 21 19:36:38 dellvol ntpd[24848]: Soliciting pool server 213.157.100.66 Oct 21 19:36:38 dellvol ntpd[24848]: Soliciting pool server 80.249.164.126 Oct 21 19:36:38 dellvol ntpd[24848]: Soliciting pool server 62.112.193.9 Oct 21 19:36:39 dellvol ntpd[24848]: Soliciting pool server 80.249.164.129 Oct 21 19:36:39 dellvol ntpd[24848]: Soliciting pool server 194.38.104.150 Oct 21 19:36:40 dellvol ntpd[24848]: Soliciting pool server 193.6.222.47 Oct 21 19:36:40 dellvol ntpd[24848]: Soliciting pool server 2a01:6ee0:1::13:f Oct 21 19:36:45 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 21 19:36:45 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:36:45 dellvol volumio[1132]: info: Preloading song: spotify:track:3Kw5w7KLZuzS9bOIXbHacb Oct 21 19:36:45 dellvol volumio[1132]: info: Exploding uri spotify:track:3Kw5w7KLZuzS9bOIXbHacb in service spop Oct 21 19:36:45 dellvol volumio[1132]: SPOTIFY: EXPLODING URI:spotify:track:3Kw5w7KLZuzS9bOIXbHacb Oct 21 19:36:46 dellvol volumio[1132]: error: Commandrouter: Cannot explode uri spotify:track:3Kw5w7KLZuzS9bOIXbHacb from service spop: Error: Spotify API method getTrack failed: WebapiRegularError: An error occurred while communicating with Spotify's Web API. Oct 21 19:36:46 dellvol volumio[1132]: Details: No token provided. Oct 21 19:36:50 dellvol go-librespot[1959]: time="2024-10-21T19:36:50+02:00" level=debug msg="fetched chunk 14/16, size: 524288" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:36:52 dellvol volumio[1132]: verbose: New Socket.io Connection to 10.0.4.55 from 10.0.4.119 UA: Mozilla/5.0 (Linux; Android 14; 2211133G Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetVisibleSources Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetQueue Oct 21 19:36:52 dellvol volumio[1132]: info: CoreStateMachine::getQueue Oct 21 19:36:52 dellvol volumio[1132]: info: CorePlayQueue::getQueue Oct 21 19:36:52 dellvol volumio[1132]: info: Listing playlists Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 21 19:36:52 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:52 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 21 19:36:54 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:36:54 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:36:54 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:36:54 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:36:54 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:36:55 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:36:56 dellvol ntpd[24848]: ntpd exiting on signal 15 (Terminated) Oct 21 19:36:56 dellvol ntpd[24848]: 89.107.253.4 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol systemd[1]: Stopping Network Time Service... Oct 21 19:36:56 dellvol ntpd[24848]: 62.112.195.56 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 193.224.163.77 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 81.0.124.200 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 81.0.124.253 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 62.112.195.26 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 195.111.92.55 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 193.227.197.2 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 82.141.152.3 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 92.249.148.253 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 213.157.100.66 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 62.112.193.9 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol systemd[1]: ntp.service: Succeeded. Oct 21 19:36:56 dellvol ntpd[24848]: 80.249.164.126 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 80.249.164.129 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 194.38.104.150 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol ntpd[24848]: 193.6.222.47 local addr 10.0.4.55 -> Oct 21 19:36:56 dellvol systemd[1]: Stopped Network Time Service. Oct 21 19:36:56 dellvol systemd[1]: Starting Network Time Service... Oct 21 19:36:56 dellvol ntpd[24885]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 21 19:36:56 dellvol ntpd[24885]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 21 19:36:56 dellvol systemd[1]: Started Network Time Service. Oct 21 19:36:56 dellvol ntpd[24891]: proto: precision = 0.060 usec (-24) Oct 21 19:36:56 dellvol ntpd[24891]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 21 19:36:56 dellvol ntpd[24891]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 21 19:36:56 dellvol ntpd[24891]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 664 days ago Oct 21 19:36:56 dellvol ntpd[24891]: Listen and drop on 0 v6wildcard [::]:123 Oct 21 19:36:56 dellvol ntpd[24891]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 21 19:36:56 dellvol ntpd[24891]: Listen normally on 2 lo 127.0.0.1:123 Oct 21 19:36:56 dellvol ntpd[24891]: Listen normally on 3 eth0 10.0.4.55:123 Oct 21 19:36:56 dellvol ntpd[24891]: Listen normally on 4 wlan0 10.0.4.67:123 Oct 21 19:36:56 dellvol ntpd[24891]: Listening on routing socket on fd #21 for interface updates Oct 21 19:36:56 dellvol ntpd[24891]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 21 19:36:56 dellvol ntpd[24891]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 21 19:36:57 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:36:57 dellvol volumio[1132]: info: CURURI: music-library Oct 21 19:36:57 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:36:57 dellvol ntpd[24891]: Soliciting pool server 89.107.253.4 Oct 21 19:36:58 dellvol ntpd[24891]: Soliciting pool server 81.0.124.253 Oct 21 19:36:58 dellvol ntpd[24891]: Soliciting pool server 195.111.92.55 Oct 21 19:36:59 dellvol ntpd[24891]: Soliciting pool server 81.0.124.200 Oct 21 19:36:59 dellvol ntpd[24891]: Soliciting pool server 62.112.195.56 Oct 21 19:36:59 dellvol ntpd[24891]: Soliciting pool server 194.38.104.150 Oct 21 19:37:00 dellvol ntpd[24891]: Soliciting pool server 193.227.197.2 Oct 21 19:37:00 dellvol ntpd[24891]: Soliciting pool server 62.112.193.9 Oct 21 19:37:00 dellvol ntpd[24891]: Soliciting pool server 82.141.152.3 Oct 21 19:37:00 dellvol ntpd[24891]: Soliciting pool server 80.249.164.129 Oct 21 19:37:01 dellvol ntpd[24891]: Soliciting pool server 213.157.100.66 Oct 21 19:37:01 dellvol ntpd[24891]: Soliciting pool server 80.249.164.126 Oct 21 19:37:01 dellvol ntpd[24891]: Soliciting pool server 193.224.163.77 Oct 21 19:37:02 dellvol ntpd[24891]: Soliciting pool server 193.6.222.47 Oct 21 19:37:02 dellvol ntpd[24891]: Soliciting pool server 62.112.195.26 Oct 21 19:37:03 dellvol ntpd[24891]: Soliciting pool server 2a01:6ee0:1::13:f Oct 21 19:37:04 dellvol go-librespot[1959]: time="2024-10-21T19:37:04+02:00" level=debug msg="fetched chunk 15/16, size: 524288" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:37:04 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:04 dellvol volumio[1132]: info: CURURI: music-library/NAS Oct 21 19:37:04 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:10 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:10 dellvol volumio[1132]: info: CURURI: music-library/NAS/www.DellVol.local Oct 21 19:37:10 dellvol volumio[1132]: error: Failed LSINFO: null Oct 21 19:37:10 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:16 dellvol go-librespot[1959]: time="2024-10-21T19:37:16+02:00" level=debug msg="fetched chunk 16/16, size: 51872" uri="spotify:track:7sujRUhyub8wjNuz6OwYy9" Oct 21 19:37:19 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:19 dellvol volumio[1132]: info: CURURI: music-library/INTERNAL Oct 21 19:37:19 dellvol volumio[1132]: error: Failed LSINFO: null Oct 21 19:37:19 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:21 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:21 dellvol volumio[1132]: info: CURURI: music-library/USB Oct 21 19:37:21 dellvol volumio[1132]: error: Failed LSINFO: null Oct 21 19:37:21 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:23 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:23 dellvol volumio[1132]: info: CURURI: music-library/NAS Oct 21 19:37:23 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:37:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:37:24 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:37:24 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:37:24 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:37:25 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:25 dellvol volumio[1132]: info: CURURI: music-library/NAS/www.DellVol.local Oct 21 19:37:25 dellvol volumio[1132]: error: Failed LSINFO: null Oct 21 19:37:25 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:27 dellvol go-librespot[1959]: time="2024-10-21T19:37:27+02:00" level=debug msg="prefetching next track" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:27 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=debug msg="selected format OGG_VORBIS_320 (10b636ac93b3516161fa26b33ab003e8c29d0353)" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=debug msg="requested aes key for file 10b636ac93b3516161fa26b33ab003e8c29d0353, gid: 3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=debug msg="fetched first chunk of 22, total size is 11442928 bytes" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=info msg="prefetched track \"Marta\" (duration: 268640ms)" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:28 dellvol go-librespot[1959]: time="2024-10-21T19:37:28+02:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:29 dellvol go-librespot[1959]: time="2024-10-21T19:37:29+02:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:29 dellvol go-librespot[1959]: time="2024-10-21T19:37:29+02:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:3IYIj430pXiZfrsZU0AHDY" Oct 21 19:37:37 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:43 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 19:37:43 dellvol volumio[1132]: info: CURURI: albums:// Oct 21 19:37:43 dellvol volumio[1132]: info: listAlbums - loading Albums from cache Oct 21 19:37:43 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:48 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 19:37:48 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:50 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 19:37:51 dellvol volumio[1132]: info: Preload queue cleared Oct 21 19:37:51 dellvol volumio[1132]: verbose: New Socket.io Connection to 10.0.4.55 from 10.0.4.119 UA: Mozilla/5.0 (Linux; Android 14; 2211133G Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetVisibleSources Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetQueue Oct 21 19:37:51 dellvol volumio[1132]: info: CoreStateMachine::getQueue Oct 21 19:37:51 dellvol volumio[1132]: info: CorePlayQueue::getQueue Oct 21 19:37:51 dellvol volumio[1132]: info: Listing playlists Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 21 19:37:51 dellvol volumio[1132]: info: Received Get System Info Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 19:37:51 dellvol volumio[1132]: info: Discovery: Getting this device information Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::volumioGetState Oct 21 19:37:51 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 21 19:37:52 dellvol volumio[1132]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 19:37:53 dellvol volumio[1132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 19:37:53 dellvol volumio[1132]: TypeError: Cannot read property 'length' of undefined Oct 21 19:37:53 dellvol volumio[1132]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 21 19:37:53 dellvol volumio[1132]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 21 19:37:53 dellvol volumio[1132]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 21 19:37:53 dellvol volumio[1132]: at Parser.emit (events.js:400:28) Oct 21 19:37:53 dellvol volumio[1132]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 21 19:37:53 dellvol volumio[1132]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 21 19:37:53 dellvol volumio[1132]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 21 19:37:53 dellvol volumio[1132]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 21 19:37:53 dellvol volumio[1132]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 21 19:37:53 dellvol volumio[1132]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 21 19:37:53 dellvol volumio[1132]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 21 19:37:53 dellvol volumio[1132]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 21 19:37:53 dellvol volumio[1132]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 21 19:37:53 dellvol volumio[1132]: at IncomingMessage.emit (events.js:412:35) Oct 21 19:37:53 dellvol volumio[1132]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 21 19:37:53 dellvol volumio[1132]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 21 19:37:53 dellvol volumio[1132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 19:37:53 dellvol sudo[24953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 19:36 Oct 21 19:37:53 dellvol sudo[24953]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"