-- Logs begin at Fri 2024-10-04 21:57:13 BST, end at Fri 2024-10-04 23:12:07 BST. -- Oct 04 23:11:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 952. Oct 04 23:11:02 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:02 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:02 minidsp-shd go-librespot[21533]: Librespot-go daemon starting... Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=info msg="generated new device id: cb13e5ec79a841c6db472e7e8949c63e9124c5c8" Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:02 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=debug msg="new websocket client" Oct 04 23:11:02 minidsp-shd volumio[715]: info: Connection to go-librespot Websocket established Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=debug msg="obtained new client token: AAACa8GQspn2lo6yiR5Kpnq5zTmBJnFl4koKodoe+3G1UMGsPpyqfS1BOwHecAhDXIdlNVQbHrRGxxpns+lQVsWEcFzAFzAbiv6H7EtgnIgKTfPlJhRpv9mHpWX+2M3x1FaNVydSr8ZyXsoGxhi9iGxqG/DxeSMKiSrp+Yyg/dVx+Woeoq8D/STldYLz1KVT0QDJRGHpaGX3sUsR1WILU5GV0388R40m2blrODj/EfK+peJJ6ZblVXFH3p/y9OY=" Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 04 23:11:02 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:02+01:00" level=debug msg="completed keyexchange" Oct 04 23:11:03 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:03+01:00" level=debug msg="completed challenge" Oct 04 23:11:03 minidsp-shd go-librespot[21533]: time="2024-10-04T23:11:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 04 23:11:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:03 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:03 minidsp-shd volumio[715]: info: Connection to go-librespot Websocket closed Oct 04 23:11:05 minidsp-shd volumio[715]: info: Getting Spotify volume Oct 04 23:11:05 minidsp-shd volumio[715]: (node:715) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:05 minidsp-shd volumio[715]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Oct 04 23:11:05 minidsp-shd volumio[715]: (node:715) 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: 285) Oct 04 23:11:05 minidsp-shd volumio[715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 04 23:11:05 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:11:05 minidsp-shd volumio[715]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Oct 04 23:11:06 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:06 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:06 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:06 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 953. Oct 04 23:11:06 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:06 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:06 minidsp-shd go-librespot[21549]: Librespot-go daemon starting... Oct 04 23:11:06 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:06+01:00" level=info msg="generated new device id: f5854efae9a2b93dc6716ffc3760c6784586a0e1" Oct 04 23:11:06 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:06+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:06 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:06+01:00" level=debug msg="obtained new client token: AAAapu+t+GHgxKcdirYjxcYRY3vYMAdYaeCfpptcH5IdN6pF5BMKEtwL5GBHMCDCxQ2yFOhDbb6lrdz77hXGOcwDfmf2PIvX5R4sBcedqvWeI6e1ft1qE/V+O+JcL0HgWjk6zmXVxNZpq9ZhyQ/dKU2QXnCkWmImG50RrgUxcNE5omibyhjmeQJ07nhGALdX1Yya3PHuZ2JKoVFAVU0QYP0iTtKYK7KToRZWooo+QIFf0ZyM8VfQg1AqqKZR25M=" Oct 04 23:11:06 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 04 23:11:06 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:06+01:00" level=debug msg="completed keyexchange" Oct 04 23:11:07 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:07+01:00" level=debug msg="completed challenge" Oct 04 23:11:07 minidsp-shd go-librespot[21549]: time="2024-10-04T23:11:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 04 23:11:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:07 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:09 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:09 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 954. Oct 04 23:11:10 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:10 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:10 minidsp-shd go-librespot[21566]: Librespot-go daemon starting... Oct 04 23:11:10 minidsp-shd go-librespot[21566]: time="2024-10-04T23:11:10+01:00" level=info msg="generated new device id: cd233299a524f2e010eec20e5d8c74291be52542" Oct 04 23:11:10 minidsp-shd go-librespot[21566]: time="2024-10-04T23:11:10+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:10 minidsp-shd go-librespot[21566]: time="2024-10-04T23:11:10+01:00" level=debug msg="obtained new client token: AAC9+yTpUzkFi5UQltCPcfUjvHzzFgtC+Bvk736vB+K8V4Oszxjr4pq88g1DPPYFcVMviZi78rqZ0ClWKkSrQGKU0p64Trjowbfoq582KiyQ0nF1uyE3PrQgNuQWRqPlPh6G0rFj0fLWhPmvmJzxhjWbQMbtak4tLRk8XLbUUYMOsKiMJ19LDrBqIXXCC9W+1WOi1yNADjnAZupk06GBb9gsJfsmZX3LFpSsbjOlfnzs+1EfrbtcaC5Pbl0CyLQ=" Oct 04 23:11:10 minidsp-shd go-librespot[21566]: time="2024-10-04T23:11:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 04 23:11:10 minidsp-shd go-librespot[21566]: time="2024-10-04T23:11:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.9:4070: connect: connection refused" Oct 04 23:11:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:12 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:12 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:14 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 955. Oct 04 23:11:14 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:14 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:14 minidsp-shd go-librespot[21582]: Librespot-go daemon starting... Oct 04 23:11:14 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:14+01:00" level=info msg="generated new device id: 5ac2e171e5071902da6f74d79265149e525d6531" Oct 04 23:11:14 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:14+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:14 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:14+01:00" level=debug msg="obtained new client token: AAAu/G52O0wy+1CW8B75TB1btIqlolvBJi/FflDIz0JbjvSfv+8iW1bGxYf6QcGw83wephBIm8DzRFNYMFQgNRw+UqH+iKxGmKjpWTVUmiHkRNgq1LBwvJx4qSFspA+NmlEqfM0xffMWcoNa5tx1u6imsuxWSIdaGicuAn8lPRxWY1899dwK8dS6AHsetlliIQAO9c7EUQRMz2eVLyg7EQdWEA4bSNniGh05bfbPnEWryk2W1g3OjEhpkiNCGrM=" Oct 04 23:11:14 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:14+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 04 23:11:14 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:14+01:00" level=debug msg="completed keyexchange" Oct 04 23:11:15 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:15+01:00" level=debug msg="completed challenge" Oct 04 23:11:15 minidsp-shd go-librespot[21582]: time="2024-10-04T23:11:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 04 23:11:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:15 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:15 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:15 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:18 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:18 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:18 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 956. Oct 04 23:11:18 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:18 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:18 minidsp-shd go-librespot[21598]: Librespot-go daemon starting... Oct 04 23:11:18 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:18+01:00" level=info msg="generated new device id: 5d2253d78a77109ee0f44992d9087d02123bb340" Oct 04 23:11:18 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:18+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:18 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:18+01:00" level=debug msg="obtained new client token: AAC1sLkMM4KVvR+T9ByAqElSAHn6r96jK3jZvRqyWQJurwq93ZEZ7LTC1nBJVW2G6mmBT1tmSiTsQXh5s9R5kCwpmKP3lBWTmnOoxCZDnArZcBxPKokjHCoGcDsVis+WV5gZUcSdcgEzG0755iat/MFXanUbqeU/ehs4XFCkkQ1u0j92e3LzHcW24j2TOtf+BzeSalKj4OZ5/46CrIqkX1UjRiwoI62d4RveuUHRlEx2ylwFZqQX9hJ0Jr9YzIw=" Oct 04 23:11:18 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 04 23:11:18 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:18+01:00" level=debug msg="completed keyexchange" Oct 04 23:11:19 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:19+01:00" level=debug msg="completed challenge" Oct 04 23:11:19 minidsp-shd go-librespot[21598]: time="2024-10-04T23:11:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 04 23:11:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:21 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:21 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 957. Oct 04 23:11:22 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:22 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:22 minidsp-shd go-librespot[21652]: Librespot-go daemon starting... Oct 04 23:11:22 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:22+01:00" level=info msg="generated new device id: 58ffc1a20d692eca51675d7acfdf5aeb52abae27" Oct 04 23:11:22 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:22+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:22 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:22+01:00" level=debug msg="obtained new client token: AABTvJGWJSKZHFt8/XLGaCpZaGSm9YCT4yb446wgik1Sxt1bfBW9R5T2H3Dw/rFQwSKZe7x0JqwAomww08uGDo6ajfusSt6BcT09j+75qjhnk7QHXPF32o89bODAlLsFbT2KnmORjYEd3sZ8AsBcNM/IUN/33v/XLadbQ9u+iWDvXWsDptELngPGWVL3/yr+NU6uAgdiGJWCrVefrxSL9KO8HDaRoDqLefTb5vas5XpO1zQgIac/MMYNwpZNLzc=" Oct 04 23:11:22 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:22+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 04 23:11:23 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:23+01:00" level=debug msg="completed keyexchange" Oct 04 23:11:23 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:23+01:00" level=debug msg="completed challenge" Oct 04 23:11:23 minidsp-shd go-librespot[21652]: time="2024-10-04T23:11:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 04 23:11:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 04 23:11:23 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 04 23:11:24 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:24 minidsp-shd volumio[715]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 04 23:11:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 04 23:11:26 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 958. Oct 04 23:11:26 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 04 23:11:26 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 04 23:11:26 minidsp-shd go-librespot[21668]: Librespot-go daemon starting... Oct 04 23:11:26 minidsp-shd go-librespot[21668]: time="2024-10-04T23:11:26+01:00" level=info msg="generated new device id: 2709a2f41879e8eea92610e9e70439dd457d1fa6" Oct 04 23:11:26 minidsp-shd go-librespot[21668]: time="2024-10-04T23:11:26+01:00" level=debug msg="stored credentials found for lbhj574own4f1tcjm2qey9a4g" Oct 04 23:11:27 minidsp-shd go-librespot[21668]: time="2024-10-04T23:11:27+01:00" level=debug msg="obtained new client token: AAB7XO1e7aFZRkuyOeY0k9NigGoKWaAAaJbF9ctHC3zIvMpJ1moy43OPEhxC2O1o21XilROJZttNMKQ0NMN5pUh2BmL7V2IgHIZgJne08myX9VRMcHRRZETW65twfPXIyfL+NzS4cJ4rZwQCYLRY/TVi4/QvSeCak67db4W5+qcKEO9mNikTpsu8gcKWbhnWZM0ZGa4JYfQYh7cQEDI8DU92YM/UwonU87JMtY8x/v+SCdJQFfQ/xb7K+ZAh" Oct 04 23:11:27 minidsp-shd volumio[715]: info: Initializing connection to go-librespot Websocket Oct 04 23:11:27 minidsp-shd go-librespot[21668]: time="2024-10-04T23:11:27+01:00" level=debug msg="new websocket client" Oct 04 23:11:27 minidsp-shd volumio[715]: info: Connection to go-librespot Websocket established Oct 04 23:11:27 minidsp-shd go-librespot[21668]: time="2024-10-04T23:11:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 04 23:11:30 minidsp-shd volumio[715]: info: Getting Spotify volume Oct 04 23:11:30 minidsp-shd volumio[715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 04 23:11:30 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:11:30 minidsp-shd volumio[715]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Oct 04 23:11:58 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.93 UA: unknown Total Clients: 7 Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 23:11:58 minidsp-shd volumio[715]: info: Discovery: Getting this device information Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:11:58 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 23:11:59 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.93 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 8 Oct 04 23:12:01 minidsp-shd sudo[21747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 23:12:01 minidsp-shd sudo[21747]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 23:12:01 minidsp-shd sudo[21747]: pam_unix(sudo:session): session closed for user root Oct 04 23:12:01 minidsp-shd sudo[21750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 23:12:01 minidsp-shd sudo[21750]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 23:12:01 minidsp-shd sudo[21750]: pam_unix(sudo:session): session closed for user root Oct 04 23:12:01 minidsp-shd volumio[715]: verbose: New Socket.io Connection to 192.168.1.210 from 192.168.1.93 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 9 Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetQueue Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreStateMachine::getQueue Oct 04 23:12:01 minidsp-shd volumio[715]: info: CorePlayQueue::getQueue Oct 04 23:12:01 minidsp-shd volumio[715]: info: Listing playlists Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 23:12:01 minidsp-shd volumio[715]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Oct 04 23:12:01 minidsp-shd volumio[715]: info: Received Get System Info Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 23:12:01 minidsp-shd volumio[715]: info: Discovery: Getting this device information Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 23:12:01 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 23:12:03 minidsp-shd volumio[715]: info: Received Get System Info Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 23:12:03 minidsp-shd volumio[715]: info: Discovery: Getting this device information Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 23:12:03 minidsp-shd volumio[715]: info: Received Get System Info Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 23:12:03 minidsp-shd volumio[715]: info: Discovery: Getting this device information Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::volumioGetState Oct 04 23:12:03 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 23:12:06 minidsp-shd volumio[715]: Searching all installed plugins Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Oct 04 23:12:06 minidsp-shd volumio[715]: info: [1728079926387] ControllerCalmRadio::searchCategories Oct 04 23:12:06 minidsp-shd volumio[715]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 23:12:06 minidsp-shd volumio[715]: TypeError: Cannot read property 'cats' of undefined Oct 04 23:12:06 minidsp-shd volumio[715]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Oct 04 23:12:06 minidsp-shd volumio[715]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Oct 04 23:12:06 minidsp-shd volumio[715]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Oct 04 23:12:06 minidsp-shd volumio[715]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 04 23:12:06 minidsp-shd volumio[715]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 04 23:12:06 minidsp-shd volumio[715]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Oct 04 23:12:06 minidsp-shd volumio[715]: at Socket.emit (events.js:400:28) Oct 04 23:12:06 minidsp-shd volumio[715]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 04 23:12:06 minidsp-shd volumio[715]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 04 23:12:06 minidsp-shd volumio[715]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 23:12:07 minidsp-shd sudo[21771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 23:11 Oct 04 23:12:07 minidsp-shd sudo[21771]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"