-- Logs begin at Sat 2025-10-25 23:29:04 UTC, end at Sat 2025-10-25 23:34:39 UTC. -- Oct 25 23:33:05 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 25 23:33:05 cholos volumio[906]: info: CURURI: music-library/INTERNAL2/Rolling Stone Oct 25 23:33:15 cholos ntpd[884]: Soliciting pool server 170.210.222.10 Oct 25 23:33:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 23:33:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 23:33:19 cholos volumio[906]: info: Discovery: Getting this device information Oct 25 23:33:19 cholos volumio[906]: info: CoreCommandRouter::volumioGetState Oct 25 23:33:19 cholos volumio[906]: info: CorePlayQueue::getTrack 0 Oct 25 23:33:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 23:33:49 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 23:33:49 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 23:33:49 cholos volumio[906]: info: Discovery: Getting this device information Oct 25 23:33:49 cholos volumio[906]: info: CoreCommandRouter::volumioGetState Oct 25 23:33:49 cholos volumio[906]: info: CorePlayQueue::getTrack 0 Oct 25 23:33:49 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 23:33:54 cholos ntpd[884]: ntpd exiting on signal 15 (Terminated) Oct 25 23:33:54 cholos ntpd[884]: 162.159.200.1 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos systemd[1]: Stopping Network Time Service... Oct 25 23:33:54 cholos ntpd[884]: 168.96.251.195 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos systemd[1]: ntp.service: Succeeded. Oct 25 23:33:54 cholos ntpd[884]: 162.159.200.123 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos systemd[1]: Stopped Network Time Service. Oct 25 23:33:54 cholos ntpd[884]: 200.11.116.10 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos ntpd[884]: 170.155.148.1 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos ntpd[884]: 170.210.222.10 local addr 192.168.0.161 -> Oct 25 23:33:54 cholos systemd[1]: Starting Network Time Service... Oct 25 23:33:54 cholos ntpd[1848]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 25 23:33:54 cholos ntpd[1848]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 25 23:33:54 cholos systemd[1]: Started Network Time Service. Oct 25 23:33:54 cholos ntpd[1854]: proto: precision = 0.075 usec (-24) Oct 25 23:33:54 cholos ntpd[1854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 25 23:33:54 cholos ntpd[1854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 25 23:33:54 cholos ntpd[1854]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1216 days ago Oct 25 23:33:54 cholos ntpd[1854]: Listen and drop on 0 v6wildcard [::]:123 Oct 25 23:33:54 cholos ntpd[1854]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 25 23:33:54 cholos ntpd[1854]: Listen normally on 2 lo 127.0.0.1:123 Oct 25 23:33:54 cholos ntpd[1854]: Listen normally on 3 wlan0 192.168.0.161:123 Oct 25 23:33:54 cholos ntpd[1854]: Listening on routing socket on fd #20 for interface updates Oct 25 23:33:54 cholos ntpd[1854]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 25 23:33:54 cholos ntpd[1854]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 25 23:33:55 cholos ntpd[1854]: Soliciting pool server 162.159.200.123 Oct 25 23:33:56 cholos ntpd[1854]: Soliciting pool server 168.96.251.226 Oct 25 23:33:56 cholos ntpd[1854]: Soliciting pool server 170.155.148.1 Oct 25 23:33:57 cholos ntpd[1854]: Soliciting pool server 200.11.116.10 Oct 25 23:33:58 cholos ntpd[1854]: Soliciting pool server 190.103.0.13 Oct 25 23:33:58 cholos ntpd[1854]: Soliciting pool server 162.159.200.1 Oct 25 23:33:59 cholos ntpd[1854]: Soliciting pool server 170.210.222.10 Oct 25 23:33:59 cholos ntpd[1854]: Soliciting pool server 2606:4700:f1::123 Oct 25 23:34:02 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:02 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:02 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:02 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:03 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:03 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:03 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:05 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:06 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:06 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:06 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:06 cholos volumio[906]: (node:906) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Oct 25 23:34:06 cholos volumio[906]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Oct 25 23:34:06 cholos volumio[906]: at /volumio/app/plugins/music_service/webradio/index.js:962:31 Oct 25 23:34:06 cholos volumio[906]: at runMicrotasks () Oct 25 23:34:06 cholos volumio[906]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Oct 25 23:34:06 cholos volumio[906]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 25 23:34:06 cholos volumio[906]: (node:906) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Oct 25 23:34:07 cholos volumio[906]: (node:906) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 25 23:34:07 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:07 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:07 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:07 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:07 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:07 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:08 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:08 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:08 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:08 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:09 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:09 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:10 cholos volumio[906]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 25 23:34:11 cholos volumio[906]: error: Search in plugin webradio timed out Oct 25 23:34:11 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:12 cholos volumio[906]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 25 23:34:13 cholos volumio[906]: Searching all installed plugins Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: , search Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 25 23:34:13 cholos volumio[906]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 25 23:34:13 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 25 23:34:14 cholos volumio[906]: info: All search sources collected, pushing search results Oct 25 23:34:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 23:34:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 23:34:19 cholos volumio[906]: info: Discovery: Getting this device information Oct 25 23:34:19 cholos volumio[906]: info: CoreCommandRouter::volumioGetState Oct 25 23:34:19 cholos volumio[906]: info: CorePlayQueue::getTrack 0 Oct 25 23:34:19 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 23:34:20 cholos volumio[906]: info: CoreCommandRouter::volumioGetState Oct 25 23:34:20 cholos volumio[906]: info: CorePlayQueue::getTrack 0 Oct 25 23:34:22 cholos sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 23:34:22 cholos sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 23:34:22 cholos sudo[1863]: pam_unix(sudo:session): session closed for user root Oct 25 23:34:22 cholos sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 23:34:22 cholos sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 23:34:22 cholos sudo[1866]: pam_unix(sudo:session): session closed for user root Oct 25 23:34:23 cholos sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 23:34:23 cholos sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 23:34:23 cholos sudo[1869]: pam_unix(sudo:session): session closed for user root Oct 25 23:34:23 cholos sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 23:34:23 cholos sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 23:34:23 cholos sudo[1872]: pam_unix(sudo:session): session closed for user root Oct 25 23:34:23 cholos volumio[906]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.117 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 7 Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::volumioGetState Oct 25 23:34:23 cholos volumio[906]: info: CorePlayQueue::getTrack 0 Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 23:34:23 cholos volumio[906]: info: Listing playlists Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 23:34:23 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 23:34:27 cholos volumio[906]: info: CoreCommandRouter::volumioGetQueue Oct 25 23:34:27 cholos volumio[906]: info: CoreStateMachine::getQueue Oct 25 23:34:27 cholos volumio[906]: info: CorePlayQueue::getQueue Oct 25 23:34:33 cholos volumio[906]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 23:34:33 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 23:34:34 cholos volumio[906]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 25 23:34:34 cholos volumio[906]: info: CURURI: music-library Oct 25 23:34:39 cholos volumio[906]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 23:34:39 cholos volumio[906]: TypeError: Cannot read property 'slice' of null Oct 25 23:34:39 cholos volumio[906]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:714:41) Oct 25 23:34:39 cholos volumio[906]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:724:7) Oct 25 23:34:39 cholos volumio[906]: at Object.onceWrapper (events.js:520:26) Oct 25 23:34:39 cholos volumio[906]: at ClientRequest.emit (events.js:400:28) Oct 25 23:34:39 cholos volumio[906]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Oct 25 23:34:39 cholos volumio[906]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Oct 25 23:34:39 cholos volumio[906]: at TLSSocket.socketOnData (_http_client.js:515:22) Oct 25 23:34:39 cholos volumio[906]: at TLSSocket.emit (events.js:400:28) Oct 25 23:34:39 cholos volumio[906]: at addChunk (internal/streams/readable.js:293:12) Oct 25 23:34:39 cholos volumio[906]: at readableAddChunk (internal/streams/readable.js:267:9) Oct 25 23:34:39 cholos volumio[906]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Oct 25 23:34:39 cholos volumio[906]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 25 23:34:39 cholos volumio[906]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 23:34:39 cholos sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 23:33 Oct 25 23:34:39 cholos sudo[1889]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 03:20:44 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="e524350c1f6dfee48683e2ce9db25952"