-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-10-21 22:20:22 CEST. -- Oct 21 22:19:03 volumio-rpi2 squeezeboxserver[1195]: [22:19:03.882] mDNSsearchCallback:691 [0x196920]: changed ip:port 192.168.178.54:5000 Oct 21 22:19:03 volumio-rpi2 squeezeboxserver[1195]: [22:19:03.915] mDNSsearchCallback:691 [0x1977c0]: changed ip:port 192.168.178.68:5000 Oct 21 22:19:03 volumio-rpi2 squeezeboxserver[1195]: [22:19:03.941] mDNSsearchCallback:691 [0x196920]: changed ip:port 192.168.178.63:5000 Oct 21 22:19:03 volumio-rpi2 squeezeboxserver[1195]: [22:19:03.980] mDNSsearchCallback:691 [0x1977c0]: changed ip:port 192.168.178.38:5000 Oct 21 22:19:04 volumio-rpi2 squeezeboxserver[1195]: [22:19:04.012] mDNSsearchCallback:691 [0x1977c0]: changed ip:port 192.168.178.68:5000 Oct 21 22:19:24 volumio-rpi2 volumio[849]: info: CoreCommandRouter::volumioGetVisibleSources Oct 21 22:19:24 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 22:19:24 volumio-rpi2 volumio[849]: info: CoreCommandRouter::volumioGetQueue Oct 21 22:19:24 volumio-rpi2 volumio[849]: info: CoreStateMachine::getQueue Oct 21 22:19:24 volumio-rpi2 volumio[849]: info: CorePlayQueue::getQueue Oct 21 22:19:26 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:19:26 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:19:32 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:19:32 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:19:35 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:19:35 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:19:44 volumio-rpi2 go-librespot[29589]: time="2024-10-21T22:19:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection timed out" Oct 21 22:19:44 volumio-rpi2 volumio[849]: (node:849) UnhandledPromiseRejectionWarning: Error: socket hang up Oct 21 22:19:44 volumio-rpi2 volumio[849]: at connResetException (internal/errors.js:607:14) Oct 21 22:19:44 volumio-rpi2 volumio[849]: at Socket.socketOnEnd (_http_client.js:493:23) Oct 21 22:19:44 volumio-rpi2 volumio[849]: at Socket.emit (events.js:327:22) Oct 21 22:19:44 volumio-rpi2 volumio[849]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 21 22:19:44 volumio-rpi2 volumio[849]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 21 22:19:44 volumio-rpi2 volumio[849]: (node:849) 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: 217) Oct 21 22:19:44 volumio-rpi2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 21 22:19:44 volumio-rpi2 volumio[849]: info: Connection to go-librespot Websocket closed Oct 21 22:19:44 volumio-rpi2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 21 22:19:46 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:19:46 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:19:47 volumio-rpi2 volumio[849]: info: Initializing connection to go-librespot Websocket Oct 21 22:19:47 volumio-rpi2 volumio[849]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 21 22:19:47 volumio-rpi2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 21 22:19:47 volumio-rpi2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Oct 21 22:19:47 volumio-rpi2 systemd[1]: Stopped go-librespot Daemon. Oct 21 22:19:47 volumio-rpi2 systemd[1]: Started go-librespot Daemon. Oct 21 22:19:47 volumio-rpi2 go-librespot[29753]: Librespot-go daemon starting... Oct 21 22:19:47 volumio-rpi2 go-librespot[29753]: time="2024-10-21T22:19:47+02:00" level=info msg="generated new device id: 467b7430b819cf16afbf99b2c83dee0285fa9713" Oct 21 22:19:47 volumio-rpi2 go-librespot[29753]: time="2024-10-21T22:19:47+02:00" level=debug msg="stored credentials found for dguoadb6n8w72g3rg3565wqra" Oct 21 22:19:48 volumio-rpi2 go-librespot[29753]: time="2024-10-21T22:19:48+02:00" level=debug msg="obtained new client token: AAB2PA48GyiKWXvWSBL7T7YrAfQDNyzbrAULGxEnA5YjEWkqY8dDvbsMQ+/XfW5WYIvb9uXCATRLEMUWtkK9lPT1ofePSTRnYrBOqLGs/zH4aMvDAh7NdS1e/3D/jQ7dhX7/AdJE59ju/KIJw9N6zJKsFD/Lq0Ti9xeWxBFu7PcswvgYLXZqbw7gxuWpU4WrtuWM1vAtNZ3R6o19KSVJKc3jB0cXnosWvmq+xPSsohc80pMbgRBhFNaM2kHpW7AmvA==" Oct 21 22:19:48 volumio-rpi2 go-librespot[29753]: time="2024-10-21T22:19:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 21 22:19:49 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Initializing connection to go-librespot Websocket Oct 21 22:19:51 volumio-rpi2 go-librespot[29753]: time="2024-10-21T22:19:51+02:00" level=debug msg="new websocket client" Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Connection to go-librespot Websocket established Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4891 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4954 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4923 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4942 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4924 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4955 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4943 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4956 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4925 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4944 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4926 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4957 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4945 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4958 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4927 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4946 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4928 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4947 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4929 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4948 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4930 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4931 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4932 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4933 Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4891 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4954 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4923 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4942 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4924 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4955 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4943 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4956 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4925 in service upnp_browser Oct 21 22:19:51 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4944 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4926 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4957 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4945 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4958 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4927 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4946 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4928 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4947 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4929 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4948 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4930 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4931 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4932 in service upnp_browser Oct 21 22:19:52 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@4-4933 in service upnp_browser Oct 21 22:19:54 volumio-rpi2 volumio[849]: info: Getting Spotify volume Oct 21 22:19:54 volumio-rpi2 volumio[849]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Oct 21 22:19:54 volumio-rpi2 volumio[849]: info: CoreCommandRouter::volumioGetState Oct 21 22:19:54 volumio-rpi2 volumio[849]: info: CorePlayQueue::getTrack 0 Oct 21 22:19:54 volumio-rpi2 volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 21 22:20:02 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:02 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:20:03 volumio-rpi2 squeezeboxserver[1195]: [22:20:03.919] mDNSsearchCallback:691 [0x196920]: changed ip:port 192.168.178.54:5000 Oct 21 22:20:03 volumio-rpi2 squeezeboxserver[1195]: [22:20:03.919] mDNSsearchCallback:691 [0x196920]: changed ip:port 192.168.178.63:5000 Oct 21 22:20:03 volumio-rpi2 squeezeboxserver[1195]: [22:20:03.956] mDNSsearchCallback:691 [0x1977c0]: changed ip:port 192.168.178.38:5000 Oct 21 22:20:04 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4891 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4847 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4849 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4851 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4853 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4854 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4855 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4856 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4857 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4858 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4860 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4859 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4861 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4862 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4863 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4865 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4866 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4867 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4869 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4870 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4871 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4872 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Preloading song: upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4873 Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4891 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4847 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4849 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4851 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4853 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4854 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4855 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4856 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4857 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4858 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4860 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4859 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4861 in service upnp_browser Oct 21 22:20:05 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4862 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4863 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4865 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4866 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4867 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4869 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4870 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4871 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4872 in service upnp_browser Oct 21 22:20:06 volumio-rpi2 volumio[849]: info: Exploding uri upnp/http://192.168.178.95:2869/upnphost/udhisapi.dll?control=uuid:535ca3fd-aee7-4005-829e-7eda9ac63361+urn:upnp-org:serviceId:ContentDirectory@14_d7fcc545-4873 in service upnp_browser Oct 21 22:20:09 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:09 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:20:12 volumio-rpi2 wpa_supplicant[744]: wlan0: WPA: Group rekeying completed with b0:f2:08:5c:75:5e [GTK=TKIP] Oct 21 22:20:13 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:13 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:20:19 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:19 volumio-rpi2 volumio[849]: info: Preload queue cleared Oct 21 22:20:21 volumio-rpi2 volumio[849]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 22:20:21 volumio-rpi2 volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 22:20:21 volumio-rpi2 volumio[849]: TypeError: Cannot read property 'length' of undefined Oct 21 22:20:21 volumio-rpi2 volumio[849]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 21 22:20:21 volumio-rpi2 volumio[849]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 21 22:20:21 volumio-rpi2 volumio[849]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at Parser.emit (events.js:315:20) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at IncomingMessage.emit (events.js:327:22) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 21 22:20:21 volumio-rpi2 volumio[849]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 21 22:20:21 volumio-rpi2 volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 22:20:22 volumio-rpi2 sudo[29777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 22:19 Oct 21 22:20:22 volumio-rpi2 sudo[29777]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"