-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sat 2025-07-05 17:42:47 +04. -- Jul 05 17:41:00 volumio-lexar volumio[1060]: info: [jellyfin-poller] Polled http://localhost:8096/: offline Jul 05 17:41:17 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.137:3000 from 192.168.2.150 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 05 17:41:23 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.137 from 192.168.2.150 UA: Mozilla/5.0 (Linux; Android 15; SM-S918B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 05 17:41:24 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.137:3000 from 192.168.2.150 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetQueue Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreStateMachine::getQueue Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getQueue Jul 05 17:41:24 volumio-lexar volumio[1060]: info: Listing playlists Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 17:41:24 volumio-lexar volumio[1060]: info: Received Get System Info Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 17:41:24 volumio-lexar volumio[1060]: info: Discovery: Getting this device information Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 05 17:41:30 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.137:3000 from 192.168.2.150 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 05 17:41:31 volumio-lexar volumio[1060]: info: [jellyfin-poller] Polled http://localhost:8096/: offline Jul 05 17:41:31 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.137 from 192.168.2.150 UA: Mozilla/5.0 (Linux; Android 15; SM-S918B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetQueue Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreStateMachine::getQueue Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CorePlayQueue::getQueue Jul 05 17:41:31 volumio-lexar volumio[1060]: info: Listing playlists Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 17:41:31 volumio-lexar volumio[1060]: info: Received Get System Info Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 17:41:31 volumio-lexar volumio[1060]: info: Discovery: Getting this device information Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:31 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 05 17:41:37 volumio-lexar volumiologrotate[635]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 05 17:41:37 volumio-lexar volumiologrotate[635]: ls: cannot access 'LEXAR': No such file or directory Jul 05 17:41:43 volumio-lexar ntpd[796]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Jul 05 17:41:44 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing configureVolumioKiosk [object Object] Jul 05 17:41:44 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , configureVolumioKiosk Jul 05 17:41:44 volumio-lexar volumio[1060]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Jul 05 17:41:44 volumio-lexar sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Jul 05 17:41:44 volumio-lexar sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:44 volumio-lexar sudo[3466]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:44 volumio-lexar volumio[1060]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Jul 05 17:41:44 volumio-lexar sudo[3470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Jul 05 17:41:44 volumio-lexar sudo[3470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:44 volumio-lexar sudo[3470]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:44 volumio-lexar volumio[1060]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Jul 05 17:41:44 volumio-lexar sudo[3473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Jul 05 17:41:44 volumio-lexar sudo[3473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:41:44 volumio-lexar systemd[1]: Stopping Volumio Kiosk... Jul 05 17:41:44 volumio-lexar startx[1292]: xinit: connection to X server lost Jul 05 17:41:44 volumio-lexar startx[1292]: [1B blob data] Jul 05 17:41:44 volumio-lexar startx[1292]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Jul 05 17:41:45 volumio-lexar startx[1292]: xinit: unexpected signal 15 Jul 05 17:41:45 volumio-lexar systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jul 05 17:41:45 volumio-lexar systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jul 05 17:41:45 volumio-lexar systemd[1]: Stopped Volumio Kiosk. Jul 05 17:41:45 volumio-lexar systemd[1]: Started Volumio Kiosk. Jul 05 17:41:45 volumio-lexar sudo[3473]: pam_unix(sudo:session): session closed for user root Jul 05 17:41:45 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:41:45 volumio-lexar startx[3485]: X.Org X Server 1.20.4 Jul 05 17:41:45 volumio-lexar startx[3485]: X Protocol Version 11, Revision 0 Jul 05 17:41:45 volumio-lexar startx[3485]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 05 17:41:45 volumio-lexar startx[3485]: Current Operating System: Linux volumio-lexar 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jul 05 17:41:45 volumio-lexar startx[3485]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:3B:1A:65 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=4b031262-668b-4bc8-a665-108aa39bcb26 imgfile=/volumio_current.sqsh bootpart=UUID=D2DD-A4A8 datapart=UUID=dfbfcdba-52ee-4d0f-8273-92f27a8119e0 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 05 17:41:45 volumio-lexar startx[3485]: Build Date: 04 April 2023 07:50:56AM Jul 05 17:41:45 volumio-lexar startx[3485]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 05 17:41:45 volumio-lexar startx[3485]: Current version of pixman: 0.36.0 Jul 05 17:41:45 volumio-lexar startx[3485]: Before reporting problems, check http://wiki.x.org Jul 05 17:41:45 volumio-lexar startx[3485]: to make sure that you have the latest version. Jul 05 17:41:45 volumio-lexar startx[3485]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 05 17:41:45 volumio-lexar startx[3485]: (++) from command line, (!!) notice, (II) informational, Jul 05 17:41:45 volumio-lexar startx[3485]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 05 17:41:45 volumio-lexar startx[3485]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jul 5 17:41:45 2025 Jul 05 17:41:45 volumio-lexar startx[3485]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 05 17:41:45 volumio-lexar startx[3485]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 05 17:41:49 volumio-lexar volumio[1060]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jul 05 17:41:49 volumio-lexar volumio[1060]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37) Jul 05 17:41:49 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Jul 05 17:41:49 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:41:49 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:41:49 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jul 05 17:41:49 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jul 05 17:41:49 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jul 05 17:41:49 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jul 05 17:41:49 volumio-lexar volumio[1060]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jul 05 17:41:49 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:41:49 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jul 05 17:41:49 volumio-lexar volumio[1060]: info: Listing playlists Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetQueue Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreStateMachine::getQueue Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CorePlayQueue::getQueue Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:41:49 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:41:49 volumio-lexar volumio[1060]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"BBC Radio 1","uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","service":""} Jul 05 17:41:49 volumio-lexar volumio[1060]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:41:49 volumio-lexar volumio[1060]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Jul 05 17:41:49 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:41:49 volumio-lexar volumio[1060]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Jul 05 17:41:49 volumio-lexar volumio[1060]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Jul 05 17:41:49 volumio-lexar volumio[1060]: (node:1060) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:41:49 volumio-lexar volumio[1060]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Jul 05 17:41:49 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:41:49 volumio-lexar volumio[1060]: (Use `node --trace-warnings ...` to show where the warning was created) Jul 05 17:41:49 volumio-lexar volumio[1060]: (node:1060) 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: 27) Jul 05 17:41:49 volumio-lexar volumio[1060]: (node:1060) [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. Jul 05 17:42:01 volumio-lexar volumio[1060]: info: [jellyfin-poller] Polled http://localhost:8096/: offline Jul 05 17:42:03 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing configSaveIdleScreenSettings [object Object] Jul 05 17:42:03 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveIdleScreenSettings Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing broadcastRefresh undefined Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , broadcastRefresh Jul 05 17:42:22 volumio-lexar volumio[1060]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jul 05 17:42:22 volumio-lexar volumio[1060]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37) Jul 05 17:42:22 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Jul 05 17:42:22 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:22 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:22 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jul 05 17:42:22 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jul 05 17:42:22 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jul 05 17:42:22 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jul 05 17:42:22 volumio-lexar volumio[1060]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jul 05 17:42:22 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:22 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to 192.168.2.136:3000 from 192.168.2.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jul 05 17:42:22 volumio-lexar volumio[1060]: info: Listing playlists Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetQueue Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreStateMachine::getQueue Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CorePlayQueue::getQueue Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:42:22 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:22 volumio-lexar volumio[1060]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"BBC Radio 1","uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","service":""} Jul 05 17:42:22 volumio-lexar volumio[1060]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:42:22 volumio-lexar volumio[1060]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Jul 05 17:42:22 volumio-lexar volumio[1060]: at runMicrotasks () Jul 05 17:42:22 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:42:22 volumio-lexar volumio[1060]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Jul 05 17:42:22 volumio-lexar volumio[1060]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Jul 05 17:42:22 volumio-lexar volumio[1060]: (node:1060) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:42:22 volumio-lexar volumio[1060]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Jul 05 17:42:22 volumio-lexar volumio[1060]: at runMicrotasks () Jul 05 17:42:22 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:42:22 volumio-lexar volumio[1060]: (node:1060) 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: 31) Jul 05 17:42:23 volumio-lexar volumio[1060]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Jul 05 17:42:23 volumio-lexar volumio[1060]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37) Jul 05 17:42:23 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Jul 05 17:42:23 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:23 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:23 volumio-lexar volumio[1060]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Jul 05 17:42:23 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Jul 05 17:42:23 volumio-lexar volumio[1060]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Jul 05 17:42:23 volumio-lexar volumio[1060]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Jul 05 17:42:23 volumio-lexar volumio[1060]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Jul 05 17:42:23 volumio-lexar volumio[1060]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Jul 05 17:42:23 volumio-lexar volumio[1060]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 05 17:42:23 volumio-lexar volumio[1060]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jul 05 17:42:23 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jul 05 17:42:24 volumio-lexar volumio[1060]: info: Listing playlists Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetQueue Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CoreStateMachine::getQueue Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getQueue Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:42:24 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:42:25 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:42:25 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:42:26 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:26 volumio-lexar volumio[1060]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"BBC Radio 1","uri":"http://a.files.bbci.co.uk/ms6/live/3441A116-B12E-4D2F-ACA8-C1984642FA4B/audio/simulcast/hls/nonuk/pc_hd_abr_v2/ak/bbc_radio_one.m3u8","service":""} Jul 05 17:42:26 volumio-lexar volumio[1060]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:42:26 volumio-lexar volumio[1060]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Jul 05 17:42:26 volumio-lexar volumio[1060]: at runMicrotasks () Jul 05 17:42:26 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:42:26 volumio-lexar volumio[1060]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Jul 05 17:42:26 volumio-lexar volumio[1060]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Jul 05 17:42:26 volumio-lexar volumio[1060]: (node:1060) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Jul 05 17:42:26 volumio-lexar volumio[1060]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Jul 05 17:42:26 volumio-lexar volumio[1060]: at runMicrotasks () Jul 05 17:42:26 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jul 05 17:42:26 volumio-lexar volumio[1060]: (node:1060) 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: 34) Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Retrieving Cloud Streaming UI Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Getting Tidal Cloud Configuration Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Getting Qobuz Cloud Configuration Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Asking plugin for UI Config Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Getting Spotify Cloud Configuration Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Asking plugin for UI Config Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Saving Spotify Acccount Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Got Tidal Cloud Configuration Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Got it Jul 05 17:42:27 volumio-lexar volumio[1060]: info: Got it Jul 05 17:42:27 volumio-lexar volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:42:27 volumio-lexar volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:42:27 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 17:42:29 volumio-lexar volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 05 17:42:29 volumio-lexar volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 05 17:42:31 volumio-lexar volumio[1060]: info: [jellyfin-poller] Polled http://localhost:8096/: offline Jul 05 17:42:34 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Jul 05 17:42:34 volumio-lexar volumio[1060]: info: [jellyfin-browse] browseUri: jellyfin Jul 05 17:42:34 volumio-lexar volumio[1060]: info: Preload queue cleared Jul 05 17:42:36 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 17:42:36 volumio-lexar volumio[1060]: info: Preload queue cleared Jul 05 17:42:37 volumio-lexar volumiologrotate[635]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 05 17:42:37 volumio-lexar volumiologrotate[635]: ls: cannot access 'LEXAR': No such file or directory Jul 05 17:42:38 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 17:42:39 volumio-lexar volumio[1060]: info: Preload queue cleared Jul 05 17:42:41 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 17:42:42 volumio-lexar volumio[1060]: info: Preload queue cleared Jul 05 17:42:46 volumio-lexar volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 17:42:46 volumio-lexar volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:42:46 volumio-lexar volumio[1060]: TypeError: Cannot read property 'length' of undefined Jul 05 17:42:46 volumio-lexar volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 05 17:42:46 volumio-lexar volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 05 17:42:46 volumio-lexar volumio[1060]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 05 17:42:46 volumio-lexar volumio[1060]: at Parser.emit (events.js:315:20) Jul 05 17:42:46 volumio-lexar volumio[1060]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 05 17:42:46 volumio-lexar volumio[1060]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 05 17:42:46 volumio-lexar volumio[1060]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 05 17:42:46 volumio-lexar volumio[1060]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 05 17:42:46 volumio-lexar volumio[1060]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 05 17:42:46 volumio-lexar volumio[1060]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 05 17:42:46 volumio-lexar volumio[1060]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 05 17:42:46 volumio-lexar volumio[1060]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 05 17:42:46 volumio-lexar volumio[1060]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 05 17:42:46 volumio-lexar volumio[1060]: at IncomingMessage.emit (events.js:327:22) Jul 05 17:42:46 volumio-lexar volumio[1060]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 05 17:42:46 volumio-lexar volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 17:42:46 volumio-lexar volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:42:47 volumio-lexar sudo[4056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 17:41 Jul 05 17:42:47 volumio-lexar sudo[4056]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"