-- Logs begin at Mon 2024-10-28 12:31:39 CET, end at Mon 2024-10-28 13:46:37 CET. -- Oct 28 13:45:51 pusic volumio[1042]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 28 13:46:01 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 13:46:01 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 13:46:04 pusic volumio[823]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 13:46:04 pusic volumio[823]: info: [youtube2] InnertubeLoader: creating Innertube instance... Oct 28 13:46:05 pusic volumio[823]: info: [youtube2] InnertubeLoader: creating Auth instance... Oct 28 13:46:05 pusic volumio[823]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 13:46:05 pusic volumio[823]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vnull/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Oct 28 13:46:05 pusic volumio[823]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Oct 28 13:46:05 pusic volumio[823]: at Generator.next () Oct 28 13:46:05 pusic volumio[823]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Oct 28 13:46:05 pusic volumio[823]: at runMicrotasks () Oct 28 13:46:05 pusic volumio[823]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 28 13:46:05 pusic volumio[823]: (node:823) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Oct 28 13:46:05 pusic volumio[823]: at Function.entries () Oct 28 13:46:05 pusic volumio[823]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Oct 28 13:46:05 pusic volumio[823]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Oct 28 13:46:05 pusic volumio[823]: at runMicrotasks () Oct 28 13:46:05 pusic volumio[823]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 28 13:46:05 pusic volumio[823]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 28 13:46:05 pusic volumio[823]: (node:823) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Oct 28 13:46:05 pusic volumio[823]: (node:823) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 28 13:46:12 pusic volumio[823]: info: CoreCommandRouter::volumioGetState Oct 28 13:46:12 pusic volumio[823]: info: CorePlayQueue::getTrack 0 Oct 28 13:46:14 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 13:46:14 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 13:46:19 pusic volumio[823]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 13:46:24 pusic volumio[1042]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 28 13:46:24 pusic ntpd[782]: 46.4.54.78 local addr 172.19.0.40 -> Oct 28 13:46:30 pusic volumio[823]: info: CALLMETHOD: music_service spop saveVolspotconnectSettings [object Object] Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectSettings Oct 28 13:46:30 pusic volumio[823]: [SpotifyConnect] Creating VLS config file Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:46:30 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:46:30 pusic volumio[823]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 28 13:46:30 pusic sudo[4221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Oct 28 13:46:30 pusic sudo[4221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 13:46:30 pusic systemd[1]: Stopping Volspotconnect2 Daemon... Oct 28 13:46:30 pusic systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 28 13:46:30 pusic systemd[1]: volspotconnect.service: Succeeded. Oct 28 13:46:30 pusic systemd[1]: Stopped Volspotconnect2 Daemon. Oct 28 13:46:30 pusic systemd[1]: Started Volspotconnect2 Daemon. Oct 28 13:46:30 pusic sudo[4221]: pam_unix(sudo:session): session closed for user root Oct 28 13:46:30 pusic volumio[823]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 28 13:46:30 pusic volumio[4223]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 28 13:46:30 pusic volumio[4223]: Reading Config from "/tmp/volspotify.toml" Oct 28 13:46:30 pusic volumio[4223]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 28 13:46:30 pusic volumio[4223]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 28 13:46:32 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 13:46:32 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 13:46:34 pusic volumio[823]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 28 13:46:34 pusic volumio[823]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 13:46:34 pusic volumio[823]: Cannot find translation for source YouTube2 Oct 28 13:46:34 pusic volumio[823]: Cannot find translation for source YouTube Music Oct 28 13:46:34 pusic volumio[823]: Cannot find translation for source 80s80s Radio Oct 28 13:46:34 pusic volumio[823]: [SpotifyConnect] Closing metadata listener Oct 28 13:46:34 pusic volumio[823]: info: Disabling plugin spop Oct 28 13:46:34 pusic volumio[823]: info: Done. Oct 28 13:46:34 pusic sudo[4227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Oct 28 13:46:34 pusic sudo[4227]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 13:46:34 pusic systemd[1]: Stopping Volspotconnect2 Daemon... Oct 28 13:46:34 pusic systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Oct 28 13:46:34 pusic systemd[1]: volspotconnect.service: Succeeded. Oct 28 13:46:34 pusic systemd[1]: Stopped Volspotconnect2 Daemon. Oct 28 13:46:34 pusic sudo[4227]: pam_unix(sudo:session): session closed for user root Oct 28 13:46:34 pusic volumio[823]: [SpotifyConnect] Vollibrespot Daemon service stoped! Oct 28 13:46:36 pusic volumio[823]: info: Checking Spotify Web API Oct 28 13:46:36 pusic volumio[823]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 13:46:36 pusic volumio[823]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 28 13:46:36 pusic volumio[823]: at healthCheck (dgram.js:897:11) Oct 28 13:46:36 pusic volumio[823]: at Socket.send (dgram.js:622:3) Oct 28 13:46:36 pusic volumio[823]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Oct 28 13:46:36 pusic volumio[823]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Oct 28 13:46:36 pusic volumio[823]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Oct 28 13:46:36 pusic volumio[823]: at listOnTimeout (internal/timers.js:554:17) Oct 28 13:46:36 pusic volumio[823]: at processTimers (internal/timers.js:497:7) { Oct 28 13:46:36 pusic volumio[823]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Oct 28 13:46:36 pusic volumio[823]: } Oct 28 13:46:36 pusic volumio[823]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 13:46:37 pusic sudo[4238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 13:45 Oct 28 13:46:37 pusic sudo[4238]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"