-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-04-20 16:37:33 CEST. -- Apr 20 16:36:00 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:36:00 volumio-frank startx[1398]: xinit: unexpected signal 15 Apr 20 16:36:00 volumio-frank systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Apr 20 16:36:00 volumio-frank systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Apr 20 16:36:00 volumio-frank systemd[1]: Stopped Volumio Kiosk. Apr 20 16:36:00 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:36:00 volumio-frank systemd[1]: Started Volumio Kiosk. Apr 20 16:36:00 volumio-frank sudo[2177]: pam_unix(sudo:session): session closed for user root Apr 20 16:36:00 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Apr 20 16:36:00 volumio-frank volumio[1025]: info: Disabling plugin now_playing Apr 20 16:36:00 volumio-frank volumio[1025]: info: Done. Apr 20 16:36:00 volumio-frank startx[2199]: X.Org X Server 1.20.4 Apr 20 16:36:00 volumio-frank startx[2199]: X Protocol Version 11, Revision 0 Apr 20 16:36:00 volumio-frank startx[2199]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Apr 20 16:36:00 volumio-frank startx[2199]: Current Operating System: Linux volumio-frank 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l Apr 20 16:36:00 volumio-frank startx[2199]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=2560 bcm2708_fb.fbheight=720 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:38:81:E9 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 Apr 20 16:36:00 volumio-frank startx[2199]: Build Date: 04 April 2023 07:50:56AM Apr 20 16:36:00 volumio-frank startx[2199]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Apr 20 16:36:00 volumio-frank startx[2199]: Current version of pixman: 0.36.0 Apr 20 16:36:00 volumio-frank startx[2199]: Before reporting problems, check http://wiki.x.org Apr 20 16:36:00 volumio-frank startx[2199]: to make sure that you have the latest version. Apr 20 16:36:00 volumio-frank startx[2199]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 20 16:36:00 volumio-frank startx[2199]: (++) from command line, (!!) notice, (II) informational, Apr 20 16:36:00 volumio-frank startx[2199]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 20 16:36:00 volumio-frank startx[2199]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Apr 20 16:36:00 2024 Apr 20 16:36:00 volumio-frank startx[2199]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 20 16:36:00 volumio-frank startx[2199]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 20 16:36:00 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 20 16:36:00 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 20 16:36:00 volumio-frank volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 20 16:36:04 volumio-frank volumio[1025]: verbose: New Socket.io Connection to 127.0.0.1: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 Total Clients: 9 Apr 20 16:36:05 volumio-frank volumio[1025]: verbose: New Socket.io Connection to 127.0.0.1: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 Total Clients: 10 Apr 20 16:36:05 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetState Apr 20 16:36:05 volumio-frank volumio[1025]: info: CorePlayQueue::getTrack 0 Apr 20 16:36:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 16:36:06 volumio-frank volumio[1025]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 20 16:36:06 volumio-frank volumio[1025]: info: Listing playlists Apr 20 16:36:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 16:36:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 16:36:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 16:36:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 16:36:07 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 16:36:07 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 20 16:36:09 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 16:36:10 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 16:36:13 volumio-frank volumiologrotate[523]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 20 16:36:13 volumio-frank volumiologrotate[523]: ls: cannot access 'FRANK': No such file or directory Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 20 16:36:22 volumio-frank volumio[1025]: info: Received Get System Version Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 16:36:22 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 16:36:23 volumio-frank volumio[1025]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 16:36:23 volumio-frank volumio[1025]: info: Received Get System Version Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 16:36:23 volumio-frank volumio[1025]: info: Received Get System Info Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 16:36:23 volumio-frank volumio[1025]: info: Discovery: Getting this device information Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetState Apr 20 16:36:23 volumio-frank volumio[1025]: info: CorePlayQueue::getTrack 0 Apr 20 16:36:23 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 16:36:34 volumio-frank volumio-remote-updater[547]: No test mode Apr 20 16:36:34 volumio-frank volumio-remote-updater[547]: No alpha test mode Apr 20 16:36:34 volumio-frank volumio[1025]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 20 16:36:34 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 20 16:36:42 volumio-frank volumio[1025]: info: CoreCommandRouter::Close All Modals sent Apr 20 16:37:01 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 16:37:01 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 20 16:37:06 volumio-frank volumio[1025]: info: Enabling plugin now_playing Apr 20 16:37:06 volumio-frank volumio[1025]: info: Loading plugin "now_playing"... Apr 20 16:37:06 volumio-frank volumio[1025]: info: PLUGIN START: now_playing Apr 20 16:37:06 volumio-frank volumio[1025]: info: [now-playing-config] Config is up to date. Apr 20 16:37:06 volumio-frank volumio[1025]: info: [now-playing-weather] Fetching API key... Apr 20 16:37:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 20 16:37:06 volumio-frank volumio[1025]: info: Discovery: Getting this device information Apr 20 16:37:06 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetState Apr 20 16:37:06 volumio-frank volumio[1025]: info: CorePlayQueue::getTrack 0 Apr 20 16:37:06 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 16:37:06 volumio-frank volumio[1025]: info: [now-playing-app] App is listening on port 4004. Apr 20 16:37:06 volumio-frank volumio[1025]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Apr 20 16:37:06 volumio-frank sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Apr 20 16:37:06 volumio-frank sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:06 volumio-frank sudo[2676]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:06 volumio-frank volumio[1025]: [sudo] password for volumio: info: [now-playing-util] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Apr 20 16:37:06 volumio-frank volumio[1025]: info: Done. Apr 20 16:37:06 volumio-frank sudo[2679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Apr 20 16:37:06 volumio-frank sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:06 volumio-frank sudo[2679]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:06 volumio-frank volumio[1025]: info: [now-playing-util] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Apr 20 16:37:06 volumio-frank sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Apr 20 16:37:06 volumio-frank sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:06 volumio-frank systemd[1]: Stopping Volumio Kiosk... Apr 20 16:37:06 volumio-frank startx[2199]: xinit: connection to X server lost Apr 20 16:37:06 volumio-frank startx[2199]: [1B blob data] Apr 20 16:37:06 volumio-frank bluetoothd[586]: Endpoint unregistered: sender=:1.55 path=/MediaEndpoint/A2DPSource Apr 20 16:37:06 volumio-frank bluetoothd[586]: Endpoint unregistered: sender=:1.55 path=/MediaEndpoint/A2DPSink Apr 20 16:37:06 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:06 volumio-frank startx[2199]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Apr 20 16:37:06 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:06 volumio-frank volumio[1025]: (node:1025) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '1' of null Apr 20 16:37:06 volumio-frank volumio[1025]: at doGet (/data/plugins/user_interface/now_playing/lib/api/openweathermap/index.js:74:60) Apr 20 16:37:06 volumio-frank volumio[1025]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 20 16:37:06 volumio-frank volumio[1025]: (node:1025) 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: 11) Apr 20 16:37:06 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:06 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank volumio[1025]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 20 16:37:07 volumio-frank startx[2199]: xinit: unexpected signal 15 Apr 20 16:37:07 volumio-frank systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Apr 20 16:37:07 volumio-frank systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Apr 20 16:37:07 volumio-frank systemd[1]: Stopped Volumio Kiosk. Apr 20 16:37:07 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:07 volumio-frank systemd[1]: Started Volumio Kiosk. Apr 20 16:37:07 volumio-frank sudo[2683]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:07 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Apr 20 16:37:07 volumio-frank startx[2706]: X.Org X Server 1.20.4 Apr 20 16:37:07 volumio-frank startx[2706]: X Protocol Version 11, Revision 0 Apr 20 16:37:07 volumio-frank startx[2706]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Apr 20 16:37:07 volumio-frank startx[2706]: Current Operating System: Linux volumio-frank 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l Apr 20 16:37:07 volumio-frank startx[2706]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=2560 bcm2708_fb.fbheight=720 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:38:81:E9 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 Apr 20 16:37:07 volumio-frank startx[2706]: Build Date: 04 April 2023 07:50:56AM Apr 20 16:37:07 volumio-frank startx[2706]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Apr 20 16:37:07 volumio-frank startx[2706]: Current version of pixman: 0.36.0 Apr 20 16:37:07 volumio-frank startx[2706]: Before reporting problems, check http://wiki.x.org Apr 20 16:37:07 volumio-frank startx[2706]: to make sure that you have the latest version. Apr 20 16:37:07 volumio-frank startx[2706]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 20 16:37:07 volumio-frank startx[2706]: (++) from command line, (!!) notice, (II) informational, Apr 20 16:37:07 volumio-frank startx[2706]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 20 16:37:07 volumio-frank startx[2706]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Apr 20 16:37:07 2024 Apr 20 16:37:07 volumio-frank startx[2706]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 20 16:37:07 volumio-frank startx[2706]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 20 16:37:07 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 20 16:37:07 volumio-frank volumio[1025]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 20 16:37:08 volumio-frank volumio[1025]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 20 16:37:10 volumio-frank volumio[1025]: info: [now-playing-weather] Fetching API key... Apr 20 16:37:10 volumio-frank volumio[1025]: 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 Total Clients: 9 Apr 20 16:37:10 volumio-frank volumio[1025]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Apr 20 16:37:10 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Apr 20 16:37:10 volumio-frank volumio[1025]: info: Listing playlists Apr 20 16:37:11 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetQueue Apr 20 16:37:11 volumio-frank volumio[1025]: info: CoreStateMachine::getQueue Apr 20 16:37:11 volumio-frank volumio[1025]: info: CorePlayQueue::getQueue Apr 20 16:37:11 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 16:37:11 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 16:37:11 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetState Apr 20 16:37:11 volumio-frank volumio[1025]: info: CorePlayQueue::getTrack 0 Apr 20 16:37:11 volumio-frank volumio[1025]: (node:1025) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '1' of null Apr 20 16:37:11 volumio-frank volumio[1025]: at doGet (/data/plugins/user_interface/now_playing/lib/api/openweathermap/index.js:74:60) Apr 20 16:37:11 volumio-frank volumio[1025]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 20 16:37:11 volumio-frank volumio[1025]: (node:1025) 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: 15) Apr 20 16:37:12 volumio-frank volumio[1025]: info: Enabling plugin spop Apr 20 16:37:12 volumio-frank volumio[1025]: info: Loading plugin "spop"... Apr 20 16:37:12 volumio-frank volumio[1025]: info: PLUGIN START: spop Apr 20 16:37:12 volumio-frank volumio[1025]: info: Checking for old spotify connect plugin installed Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 16:37:12 volumio-frank volumio[1025]: [SpotifyConnect] Creating VLS config file Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:12 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:12 volumio-frank volumio[1025]: [SpotifyConnect] Starting metadata listener Apr 20 16:37:12 volumio-frank sudo[2932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 20 16:37:12 volumio-frank sudo[2932]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:12 volumio-frank volumio[1025]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 20 16:37:12 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:12 volumio-frank sudo[2932]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:12 volumio-frank volumio[2934]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:12 volumio-frank volumio[2934]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:12 volumio-frank volumio[2934]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:12 volumio-frank volumio[1025]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 20 16:37:12 volumio-frank volumio[1025]: [Metrics] SpotifyConnect: 0s 144.53ms Apr 20 16:37:12 volumio-frank volumio[1025]: info: Done. Apr 20 16:37:12 volumio-frank volumio[2934]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:12 volumio-frank volumio[2934]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:13 volumio-frank volumio[2934]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 20 16:37:13 volumio-frank volumio[2934]: stack backtrace: Apr 20 16:37:13 volumio-frank volumio[2934]: 0: 0x988444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 20 16:37:13 volumio-frank volumio[2934]: 1: 0x988444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 20 16:37:13 volumio-frank volumio[2934]: 2: 0x988444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Apr 20 16:37:13 volumio-frank volumio[2934]: 3: 0x988444 - ::fmt::h33b23b1d14f668ff Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Apr 20 16:37:13 volumio-frank volumio[2934]: 4: 0x9b10dc - core::fmt::write::h6320fb0e6ac90e58 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Apr 20 16:37:13 volumio-frank volumio[2934]: 5: 0x9804c4 - std::io::Write::write_fmt::h33b25d4810ca481f Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Apr 20 16:37:13 volumio-frank volumio[2934]: 6: 0x98aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Apr 20 16:37:13 volumio-frank volumio[2934]: 7: 0x98aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Apr 20 16:37:13 volumio-frank volumio[2934]: 8: 0x98aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Apr 20 16:37:13 volumio-frank volumio[2934]: 9: 0x98ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Apr 20 16:37:13 volumio-frank volumio[2934]: 10: 0x98b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Apr 20 16:37:13 volumio-frank volumio[2934]: 11: 0x98b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Apr 20 16:37:13 volumio-frank volumio[2934]: 12: 0x9889c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Apr 20 16:37:13 volumio-frank volumio[2934]: 13: 0x98b098 - rust_begin_unwind Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Apr 20 16:37:13 volumio-frank volumio[2934]: 14: 0x46e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Apr 20 16:37:13 volumio-frank volumio[2934]: 15: 0x65a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Apr 20 16:37:13 volumio-frank volumio[2934]: 16: 0x6d8d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Apr 20 16:37:13 volumio-frank volumio[2934]: 17: 0x65b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Apr 20 16:37:13 volumio-frank volumio[2934]: 18: 0x6d15f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Apr 20 16:37:13 volumio-frank volumio[2934]: 19: 0x495b34 - ::poll::h5fd9e3bcdeb25ad9 Apr 20 16:37:13 volumio-frank volumio[2934]: 20: 0x4b33dc - futures::task_impl::std::set::h8f754e80bd562d0a Apr 20 16:37:13 volumio-frank volumio[2934]: 21: 0x4ca42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Apr 20 16:37:13 volumio-frank volumio[2934]: 22: 0x4af99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Apr 20 16:37:13 volumio-frank volumio[2934]: 23: 0x4ca6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Apr 20 16:37:13 volumio-frank volumio[2934]: 24: 0x4b2880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Apr 20 16:37:13 volumio-frank volumio[2934]: 25: 0x498eb0 - vollibrespot::main::h35555355ed947dd1 Apr 20 16:37:13 volumio-frank volumio[2934]: 26: 0x4b8c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Apr 20 16:37:13 volumio-frank volumio[2934]: 27: 0x4de52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Apr 20 16:37:13 volumio-frank volumio[2934]: 28: 0x987b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Apr 20 16:37:13 volumio-frank volumio[2934]: 29: 0x987b28 - std::panicking::try::do_call::hdf194554a41aa3cc Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:13 volumio-frank volumio[2934]: 30: 0x987b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:13 volumio-frank volumio[2934]: 31: 0x987b28 - std::panic::catch_unwind::h45f392a39f2fe48c Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:13 volumio-frank volumio[2934]: 32: 0x987b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Apr 20 16:37:13 volumio-frank volumio[2934]: 33: 0x987b28 - std::panicking::try::do_call::hd126f715b519ff2a Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:13 volumio-frank volumio[2934]: 34: 0x987b28 - std::panicking::try::h80b7148ce71893dc Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:13 volumio-frank volumio[2934]: 35: 0x987b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:13 volumio-frank volumio[2934]: 36: 0x987b28 - std::rt::lang_start_internal::h3daa586a0b835507 Apr 20 16:37:13 volumio-frank volumio[2934]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Apr 20 16:37:13 volumio-frank volumio[2934]: 37: 0x49ae80 - main Apr 20 16:37:13 volumio-frank volumio[2934]: 38: 0xb6c60768 - __libc_start_main Apr 20 16:37:13 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Apr 20 16:37:13 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 20 16:37:13 volumio-frank volumiologrotate[523]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 20 16:37:13 volumio-frank volumiologrotate[523]: ls: cannot access 'FRANK': No such file or directory Apr 20 16:37:15 volumio-frank systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 20 16:37:15 volumio-frank systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Apr 20 16:37:15 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:15 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:15 volumio-frank volumio[3165]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:15 volumio-frank volumio[3165]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:15 volumio-frank volumio[3165]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:15 volumio-frank volumio[3165]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:15 volumio-frank volumio[3165]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:15 volumio-frank volumio[3165]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 20 16:37:15 volumio-frank volumio[3165]: stack backtrace: Apr 20 16:37:15 volumio-frank volumio[3165]: 0: 0xa28444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 20 16:37:15 volumio-frank volumio[3165]: 1: 0xa28444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 20 16:37:15 volumio-frank volumio[3165]: 2: 0xa28444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Apr 20 16:37:15 volumio-frank volumio[3165]: 3: 0xa28444 - ::fmt::h33b23b1d14f668ff Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Apr 20 16:37:15 volumio-frank volumio[3165]: 4: 0xa510dc - core::fmt::write::h6320fb0e6ac90e58 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Apr 20 16:37:15 volumio-frank volumio[3165]: 5: 0xa204c4 - std::io::Write::write_fmt::h33b25d4810ca481f Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Apr 20 16:37:15 volumio-frank volumio[3165]: 6: 0xa2aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Apr 20 16:37:15 volumio-frank volumio[3165]: 7: 0xa2aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Apr 20 16:37:15 volumio-frank volumio[3165]: 8: 0xa2aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Apr 20 16:37:15 volumio-frank volumio[3165]: 9: 0xa2ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Apr 20 16:37:15 volumio-frank volumio[3165]: 10: 0xa2b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Apr 20 16:37:15 volumio-frank volumio[3165]: 11: 0xa2b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Apr 20 16:37:15 volumio-frank volumio[3165]: 12: 0xa289c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Apr 20 16:37:15 volumio-frank volumio[3165]: 13: 0xa2b098 - rust_begin_unwind Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Apr 20 16:37:15 volumio-frank volumio[3165]: 14: 0x50e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Apr 20 16:37:15 volumio-frank volumio[3165]: 15: 0x6fa930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Apr 20 16:37:15 volumio-frank volumio[3165]: 16: 0x778d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Apr 20 16:37:15 volumio-frank volumio[3165]: 17: 0x6fb53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Apr 20 16:37:15 volumio-frank volumio[3165]: 18: 0x7715f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Apr 20 16:37:15 volumio-frank volumio[3165]: 19: 0x535b34 - ::poll::h5fd9e3bcdeb25ad9 Apr 20 16:37:15 volumio-frank volumio[3165]: 20: 0x5533dc - futures::task_impl::std::set::h8f754e80bd562d0a Apr 20 16:37:15 volumio-frank volumio[3165]: 21: 0x56a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Apr 20 16:37:15 volumio-frank volumio[3165]: 22: 0x54f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Apr 20 16:37:15 volumio-frank volumio[3165]: 23: 0x56a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Apr 20 16:37:15 volumio-frank volumio[3165]: 24: 0x552880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Apr 20 16:37:15 volumio-frank volumio[3165]: 25: 0x538eb0 - vollibrespot::main::h35555355ed947dd1 Apr 20 16:37:15 volumio-frank volumio[3165]: 26: 0x558c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Apr 20 16:37:15 volumio-frank volumio[3165]: 27: 0x57e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Apr 20 16:37:15 volumio-frank volumio[3165]: 28: 0xa27b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Apr 20 16:37:15 volumio-frank volumio[3165]: 29: 0xa27b28 - std::panicking::try::do_call::hdf194554a41aa3cc Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:15 volumio-frank volumio[3165]: 30: 0xa27b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:15 volumio-frank volumio[3165]: 31: 0xa27b28 - std::panic::catch_unwind::h45f392a39f2fe48c Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:15 volumio-frank volumio[3165]: 32: 0xa27b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Apr 20 16:37:15 volumio-frank volumio[3165]: 33: 0xa27b28 - std::panicking::try::do_call::hd126f715b519ff2a Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:15 volumio-frank volumio[3165]: 34: 0xa27b28 - std::panicking::try::h80b7148ce71893dc Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:15 volumio-frank volumio[3165]: 35: 0xa27b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:15 volumio-frank volumio[3165]: 36: 0xa27b28 - std::rt::lang_start_internal::h3daa586a0b835507 Apr 20 16:37:15 volumio-frank volumio[3165]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Apr 20 16:37:15 volumio-frank volumio[3165]: 37: 0x53ae80 - main Apr 20 16:37:15 volumio-frank volumio[3165]: 38: 0xb6c9b768 - __libc_start_main Apr 20 16:37:15 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Apr 20 16:37:15 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 20 16:37:16 volumio-frank volumio[1025]: info: Checking Spotify Web API Apr 20 16:37:17 volumio-frank systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 20 16:37:17 volumio-frank systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 2. Apr 20 16:37:17 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:17 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:17 volumio-frank volumio[3171]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:17 volumio-frank volumio[3171]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:17 volumio-frank volumio[3171]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:17 volumio-frank volumio[3171]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:17 volumio-frank volumio[3171]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:18 volumio-frank volumio[3171]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 20 16:37:18 volumio-frank volumio[3171]: stack backtrace: Apr 20 16:37:18 volumio-frank volumio[3171]: 0: 0x978444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 20 16:37:18 volumio-frank volumio[3171]: 1: 0x978444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 20 16:37:18 volumio-frank volumio[3171]: 2: 0x978444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Apr 20 16:37:18 volumio-frank volumio[3171]: 3: 0x978444 - ::fmt::h33b23b1d14f668ff Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Apr 20 16:37:18 volumio-frank volumio[3171]: 4: 0x9a10dc - core::fmt::write::h6320fb0e6ac90e58 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Apr 20 16:37:18 volumio-frank volumio[3171]: 5: 0x9704c4 - std::io::Write::write_fmt::h33b25d4810ca481f Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Apr 20 16:37:18 volumio-frank volumio[3171]: 6: 0x97aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Apr 20 16:37:18 volumio-frank volumio[3171]: 7: 0x97aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Apr 20 16:37:18 volumio-frank volumio[3171]: 8: 0x97aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Apr 20 16:37:18 volumio-frank volumio[3171]: 9: 0x97ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Apr 20 16:37:18 volumio-frank volumio[3171]: 10: 0x97b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Apr 20 16:37:18 volumio-frank volumio[3171]: 11: 0x97b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Apr 20 16:37:18 volumio-frank volumio[3171]: 12: 0x9789c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Apr 20 16:37:18 volumio-frank volumio[3171]: 13: 0x97b098 - rust_begin_unwind Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Apr 20 16:37:18 volumio-frank volumio[3171]: 14: 0x45e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Apr 20 16:37:18 volumio-frank volumio[3171]: 15: 0x64a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Apr 20 16:37:18 volumio-frank volumio[3171]: 16: 0x6c8d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Apr 20 16:37:18 volumio-frank volumio[3171]: 17: 0x64b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Apr 20 16:37:18 volumio-frank volumio[3171]: 18: 0x6c15f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Apr 20 16:37:18 volumio-frank volumio[3171]: 19: 0x485b34 - ::poll::h5fd9e3bcdeb25ad9 Apr 20 16:37:18 volumio-frank volumio[3171]: 20: 0x4a33dc - futures::task_impl::std::set::h8f754e80bd562d0a Apr 20 16:37:18 volumio-frank volumio[3171]: 21: 0x4ba42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Apr 20 16:37:18 volumio-frank volumio[3171]: 22: 0x49f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Apr 20 16:37:18 volumio-frank volumio[3171]: 23: 0x4ba6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Apr 20 16:37:18 volumio-frank volumio[3171]: 24: 0x4a2880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Apr 20 16:37:18 volumio-frank volumio[3171]: 25: 0x488eb0 - vollibrespot::main::h35555355ed947dd1 Apr 20 16:37:18 volumio-frank volumio[3171]: 26: 0x4a8c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Apr 20 16:37:18 volumio-frank volumio[3171]: 27: 0x4ce52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Apr 20 16:37:18 volumio-frank volumio[3171]: 28: 0x977b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Apr 20 16:37:18 volumio-frank volumio[3171]: 29: 0x977b28 - std::panicking::try::do_call::hdf194554a41aa3cc Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:18 volumio-frank volumio[3171]: 30: 0x977b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:18 volumio-frank volumio[3171]: 31: 0x977b28 - std::panic::catch_unwind::h45f392a39f2fe48c Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:18 volumio-frank volumio[3171]: 32: 0x977b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Apr 20 16:37:18 volumio-frank volumio[3171]: 33: 0x977b28 - std::panicking::try::do_call::hd126f715b519ff2a Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:18 volumio-frank volumio[3171]: 34: 0x977b28 - std::panicking::try::h80b7148ce71893dc Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:18 volumio-frank volumio[3171]: 35: 0x977b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:18 volumio-frank volumio[3171]: 36: 0x977b28 - std::rt::lang_start_internal::h3daa586a0b835507 Apr 20 16:37:18 volumio-frank volumio[3171]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Apr 20 16:37:18 volumio-frank volumio[3171]: 37: 0x48ae80 - main Apr 20 16:37:18 volumio-frank volumio[3171]: 38: 0xb6bbd768 - __libc_start_main Apr 20 16:37:18 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Apr 20 16:37:18 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 20 16:37:20 volumio-frank systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 20 16:37:20 volumio-frank systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 3. Apr 20 16:37:20 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:20 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:20 volumio-frank volumio[3177]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:20 volumio-frank volumio[3177]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:20 volumio-frank volumio[3177]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:20 volumio-frank volumio[3177]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:20 volumio-frank volumio[3177]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:20 volumio-frank volumio[3177]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 20 16:37:20 volumio-frank volumio[3177]: stack backtrace: Apr 20 16:37:20 volumio-frank volumio[3177]: 0: 0xa58444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 20 16:37:20 volumio-frank volumio[3177]: 1: 0xa58444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 20 16:37:20 volumio-frank volumio[3177]: 2: 0xa58444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Apr 20 16:37:20 volumio-frank volumio[3177]: 3: 0xa58444 - ::fmt::h33b23b1d14f668ff Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Apr 20 16:37:20 volumio-frank volumio[3177]: 4: 0xa810dc - core::fmt::write::h6320fb0e6ac90e58 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Apr 20 16:37:20 volumio-frank volumio[3177]: 5: 0xa504c4 - std::io::Write::write_fmt::h33b25d4810ca481f Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Apr 20 16:37:20 volumio-frank volumio[3177]: 6: 0xa5aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Apr 20 16:37:20 volumio-frank volumio[3177]: 7: 0xa5aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Apr 20 16:37:20 volumio-frank volumio[3177]: 8: 0xa5aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Apr 20 16:37:20 volumio-frank volumio[3177]: 9: 0xa5ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Apr 20 16:37:20 volumio-frank volumio[3177]: 10: 0xa5b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Apr 20 16:37:20 volumio-frank volumio[3177]: 11: 0xa5b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Apr 20 16:37:20 volumio-frank volumio[3177]: 12: 0xa589c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Apr 20 16:37:20 volumio-frank volumio[3177]: 13: 0xa5b098 - rust_begin_unwind Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Apr 20 16:37:20 volumio-frank volumio[3177]: 14: 0x53e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Apr 20 16:37:20 volumio-frank volumio[3177]: 15: 0x72a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Apr 20 16:37:20 volumio-frank volumio[3177]: 16: 0x7a8d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Apr 20 16:37:20 volumio-frank volumio[3177]: 17: 0x72b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Apr 20 16:37:20 volumio-frank volumio[3177]: 18: 0x7a15f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Apr 20 16:37:20 volumio-frank volumio[3177]: 19: 0x565b34 - ::poll::h5fd9e3bcdeb25ad9 Apr 20 16:37:20 volumio-frank volumio[3177]: 20: 0x5833dc - futures::task_impl::std::set::h8f754e80bd562d0a Apr 20 16:37:20 volumio-frank volumio[3177]: 21: 0x59a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Apr 20 16:37:20 volumio-frank volumio[3177]: 22: 0x57f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Apr 20 16:37:20 volumio-frank volumio[3177]: 23: 0x59a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Apr 20 16:37:20 volumio-frank volumio[3177]: 24: 0x582880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Apr 20 16:37:20 volumio-frank volumio[3177]: 25: 0x568eb0 - vollibrespot::main::h35555355ed947dd1 Apr 20 16:37:20 volumio-frank volumio[3177]: 26: 0x588c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Apr 20 16:37:20 volumio-frank volumio[3177]: 27: 0x5ae52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Apr 20 16:37:20 volumio-frank volumio[3177]: 28: 0xa57b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Apr 20 16:37:20 volumio-frank volumio[3177]: 29: 0xa57b28 - std::panicking::try::do_call::hdf194554a41aa3cc Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:20 volumio-frank volumio[3177]: 30: 0xa57b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:20 volumio-frank volumio[3177]: 31: 0xa57b28 - std::panic::catch_unwind::h45f392a39f2fe48c Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:20 volumio-frank volumio[3177]: 32: 0xa57b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Apr 20 16:37:20 volumio-frank volumio[3177]: 33: 0xa57b28 - std::panicking::try::do_call::hd126f715b519ff2a Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:20 volumio-frank volumio[3177]: 34: 0xa57b28 - std::panicking::try::h80b7148ce71893dc Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:20 volumio-frank volumio[3177]: 35: 0xa57b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:20 volumio-frank volumio[3177]: 36: 0xa57b28 - std::rt::lang_start_internal::h3daa586a0b835507 Apr 20 16:37:20 volumio-frank volumio[3177]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Apr 20 16:37:20 volumio-frank volumio[3177]: 37: 0x56ae80 - main Apr 20 16:37:20 volumio-frank volumio[3177]: 38: 0xb6ca0768 - __libc_start_main Apr 20 16:37:20 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Apr 20 16:37:20 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 20 16:37:22 volumio-frank systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 20 16:37:22 volumio-frank systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 4. Apr 20 16:37:22 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:22 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:22 volumio-frank volumio[3183]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:22 volumio-frank volumio[3183]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:22 volumio-frank volumio[3183]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:22 volumio-frank volumio[3183]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:22 volumio-frank volumio[3183]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:23 volumio-frank volumio[3183]: thread 'main' panicked at 'Authentication failed with reason: BadCredentials', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/core/src/connection/mod.rs:93:21 Apr 20 16:37:23 volumio-frank volumio[3183]: stack backtrace: Apr 20 16:37:23 volumio-frank volumio[3183]: 0: 0x978444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 20 16:37:23 volumio-frank volumio[3183]: 1: 0x978444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 20 16:37:23 volumio-frank volumio[3183]: 2: 0x978444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Apr 20 16:37:23 volumio-frank volumio[3183]: 3: 0x978444 - ::fmt::h33b23b1d14f668ff Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Apr 20 16:37:23 volumio-frank volumio[3183]: 4: 0x9a10dc - core::fmt::write::h6320fb0e6ac90e58 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Apr 20 16:37:23 volumio-frank volumio[3183]: 5: 0x9704c4 - std::io::Write::write_fmt::h33b25d4810ca481f Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Apr 20 16:37:23 volumio-frank volumio[3183]: 6: 0x97aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Apr 20 16:37:23 volumio-frank volumio[3183]: 7: 0x97aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Apr 20 16:37:23 volumio-frank volumio[3183]: 8: 0x97aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Apr 20 16:37:23 volumio-frank volumio[3183]: 9: 0x97ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Apr 20 16:37:23 volumio-frank volumio[3183]: 10: 0x97b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Apr 20 16:37:23 volumio-frank volumio[3183]: 11: 0x97b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Apr 20 16:37:23 volumio-frank volumio[3183]: 12: 0x9789c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Apr 20 16:37:23 volumio-frank volumio[3183]: 13: 0x97b098 - rust_begin_unwind Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Apr 20 16:37:23 volumio-frank volumio[3183]: 14: 0x45e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Apr 20 16:37:23 volumio-frank volumio[3183]: 15: 0x64a930 - futures::future::chain::Chain::poll::hb08394d341f2da5e Apr 20 16:37:23 volumio-frank volumio[3183]: 16: 0x6c8d4c - as futures::future::Future>::poll::h0222f8b810b74ccb Apr 20 16:37:23 volumio-frank volumio[3183]: 17: 0x64b53c - futures::future::chain::Chain::poll::hd3215a5f3268adbc Apr 20 16:37:23 volumio-frank volumio[3183]: 18: 0x6c15f0 - as futures::future::Future>::poll::hc28eaa01d8d9029a Apr 20 16:37:23 volumio-frank volumio[3183]: 19: 0x485b34 - ::poll::h5fd9e3bcdeb25ad9 Apr 20 16:37:23 volumio-frank volumio[3183]: 20: 0x4a33dc - futures::task_impl::std::set::h8f754e80bd562d0a Apr 20 16:37:23 volumio-frank volumio[3183]: 21: 0x4ba42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Apr 20 16:37:23 volumio-frank volumio[3183]: 22: 0x49f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Apr 20 16:37:23 volumio-frank volumio[3183]: 23: 0x4ba6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Apr 20 16:37:23 volumio-frank volumio[3183]: 24: 0x4a2880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Apr 20 16:37:23 volumio-frank volumio[3183]: 25: 0x488eb0 - vollibrespot::main::h35555355ed947dd1 Apr 20 16:37:23 volumio-frank volumio[3183]: 26: 0x4a8c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Apr 20 16:37:23 volumio-frank volumio[3183]: 27: 0x4ce52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Apr 20 16:37:23 volumio-frank volumio[3183]: 28: 0x977b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Apr 20 16:37:23 volumio-frank volumio[3183]: 29: 0x977b28 - std::panicking::try::do_call::hdf194554a41aa3cc Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:23 volumio-frank volumio[3183]: 30: 0x977b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:23 volumio-frank volumio[3183]: 31: 0x977b28 - std::panic::catch_unwind::h45f392a39f2fe48c Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:23 volumio-frank volumio[3183]: 32: 0x977b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Apr 20 16:37:23 volumio-frank volumio[3183]: 33: 0x977b28 - std::panicking::try::do_call::hd126f715b519ff2a Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Apr 20 16:37:23 volumio-frank volumio[3183]: 34: 0x977b28 - std::panicking::try::h80b7148ce71893dc Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Apr 20 16:37:23 volumio-frank volumio[3183]: 35: 0x977b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Apr 20 16:37:23 volumio-frank volumio[3183]: 36: 0x977b28 - std::rt::lang_start_internal::h3daa586a0b835507 Apr 20 16:37:23 volumio-frank volumio[3183]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Apr 20 16:37:23 volumio-frank volumio[3183]: 37: 0x48ae80 - main Apr 20 16:37:23 volumio-frank volumio[3183]: 38: 0xb6c99768 - __libc_start_main Apr 20 16:37:23 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Apr 20 16:37:23 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Apr 20 16:37:24 volumio-frank volumio[1025]: info: Enabling plugin peppy_screensaver Apr 20 16:37:24 volumio-frank volumio[1025]: info: Loading plugin "peppy_screensaver"... Apr 20 16:37:25 volumio-frank systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Apr 20 16:37:25 volumio-frank systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 5. Apr 20 16:37:25 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:25 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:25 volumio-frank volumio[3189]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:25 volumio-frank volumio[3189]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:25 volumio-frank volumio[3189]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:25 volumio-frank volumio[3189]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:25 volumio-frank volumio[3189]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:25 volumio-frank volumio[1025]: info: Applying required configuration parameters for plugin peppy_screensaver Apr 20 16:37:25 volumio-frank volumio[1025]: info: Preparing to generate the ALSA configuration file Apr 20 16:37:25 volumio-frank volumio[1025]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 20 16:37:25 volumio-frank volumio[1025]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 20 16:37:25 volumio-frank volumio[1025]: info: Reading ALSA contributions from plugins. Apr 20 16:37:25 volumio-frank volumio[1025]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 20 16:37:26 volumio-frank volumio[1025]: info: Asound.conf file written Apr 20 16:37:26 volumio-frank sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 20 16:37:26 volumio-frank sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3197]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: info: Output device has changed, restarting MPD Apr 20 16:37:26 volumio-frank sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 16:37:26 volumio-frank sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3202]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: info: Output device has changed, restarting Shairport Sync Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:26 volumio-frank sudo[3205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 16:37:26 volumio-frank sudo[3205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Music Player Daemon... Apr 20 16:37:26 volumio-frank volumio[1025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Creating VLS config file Apr 20 16:37:26 volumio-frank systemd[1]: mpd.service: Succeeded. Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Music Player Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Creating VLS config file Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: PLUGIN START: peppy_screensaver Apr 20 16:37:26 volumio-frank volumio[1025]: info: Loading i18n strings for locale de Apr 20 16:37:26 volumio-frank systemd[1]: Starting Music Player Daemon... Apr 20 16:37:26 volumio-frank volumio[1025]: info: /tmp/myfifo created Apr 20 16:37:26 volumio-frank sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Apr 20 16:37:26 volumio-frank sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3216]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: info: snd-dummy loaded Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 20 16:37:26 volumio-frank volumio[1025]: info: Preparing to generate the ALSA configuration file Apr 20 16:37:26 volumio-frank volumio[1025]: info: Done. Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 20 16:37:26 volumio-frank sudo[3226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 20 16:37:26 volumio-frank sudo[3226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank volumio[1025]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 20 16:37:26 volumio-frank volumio[1025]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 20 16:37:26 volumio-frank volumio[1025]: info: Reading ALSA contributions from plugins. Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Volspotconnect2 Daemon... Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:26 volumio-frank volumio[1025]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Succeeded. Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: info: MPD Permissions set Apr 20 16:37:26 volumio-frank sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 20 16:37:26 volumio-frank sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::volumioGetState Apr 20 16:37:26 volumio-frank volumio[1025]: info: CorePlayQueue::getTrack 0 Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: info: Starting Shairport Sync Apr 20 16:37:26 volumio-frank volumio[3230]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Volspotconnect2 Daemon... Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:26 volumio-frank sudo[3226]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Succeeded. Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank sudo[3229]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 20 16:37:26 volumio-frank volumio[3239]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:26 volumio-frank volumio[3239]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:26 volumio-frank volumio[3239]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:26 volumio-frank volumio[1025]: info: Asound.conf file unchanged, so no further update is needed Apr 20 16:37:26 volumio-frank volumio[1025]: info: Output device has changed, restarting MPD Apr 20 16:37:26 volumio-frank sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 16:37:26 volumio-frank sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 16:37:26 volumio-frank systemd[1]: shairport-sync.service: Succeeded. Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 16:37:26 volumio-frank volumio[3239]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 20 16:37:26 volumio-frank volumio[1025]: info: Output device has changed, restarting Shairport Sync Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:26 volumio-frank sudo[3247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /data/configuration/miscellanea/peppy_screensaver/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Apr 20 16:37:26 volumio-frank sudo[3247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 16:37:26 volumio-frank systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 16:37:26 volumio-frank sudo[3248]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3247]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank sudo[3248]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank sudo[3254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 16:37:26 volumio-frank sudo[3254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank volumio[3239]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 20 16:37:26 volumio-frank sudo[3238]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 16:37:26 volumio-frank systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:26 volumio-frank systemd[1]: mpd.service: Succeeded. Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Creating VLS config file Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Music Player Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 20 16:37:26 volumio-frank systemd[1]: Starting Music Player Daemon... Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Creating VLS config file Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 20 16:37:26 volumio-frank sudo[3268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 20 16:37:26 volumio-frank sudo[3268]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Volspotconnect2 Daemon... Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Succeeded. Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank systemd[1]: Started Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank sudo[3271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank sudo[3268]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank sudo[3271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 20 16:37:26 volumio-frank volumio[1025]: info: MPD Permissions set Apr 20 16:37:26 volumio-frank volumio[1025]: info: Shairport-Sync Started Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Apr 20 16:37:26 volumio-frank volumio[3272]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 20 16:37:26 volumio-frank volumio[3272]: Reading Config from "/tmp/volspotify.toml" Apr 20 16:37:26 volumio-frank volumio[3272]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 20 16:37:26 volumio-frank systemd[1]: Stopping Volspotconnect2 Daemon... Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Succeeded. Apr 20 16:37:26 volumio-frank systemd[1]: Stopped Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Start request repeated too quickly. Apr 20 16:37:26 volumio-frank systemd[1]: volspotconnect.service: Failed with result 'start-limit-hit'. Apr 20 16:37:26 volumio-frank systemd[1]: Failed to start Volspotconnect2 Daemon. Apr 20 16:37:26 volumio-frank sudo[3271]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:26 volumio-frank volumio[1025]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 20 16:37:26 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:26 volumio-frank sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 16:37:26 volumio-frank sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:26 volumio-frank sudo[3276]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:26 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: MPD Permissions set Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: Starting Shairport Sync Apr 20 16:37:27 volumio-frank sudo[3287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 16:37:27 volumio-frank sudo[3287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:27 volumio-frank systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 16:37:27 volumio-frank systemd[1]: shairport-sync.service: Succeeded. Apr 20 16:37:27 volumio-frank systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:27 volumio-frank systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 16:37:27 volumio-frank sudo[3287]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:27 volumio-frank volumio[1025]: info: Shairport-Sync Started Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 16:37:27 volumio-frank volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 20 16:37:27 volumio-frank sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 16:37:27 volumio-frank sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:27 volumio-frank systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 20 16:37:27 volumio-frank systemd[1]: mpd.service: Succeeded. Apr 20 16:37:27 volumio-frank systemd[1]: Stopped Music Player Daemon. Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 16:37:27 volumio-frank systemd[1]: Starting Music Player Daemon... Apr 20 16:37:27 volumio-frank volumio[1025]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 16:37:28 volumio-frank mpd[3295]: Apr 20 16:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 16:37:28 volumio-frank systemd[1]: Started Music Player Daemon. Apr 20 16:37:28 volumio-frank sudo[3205]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:28 volumio-frank sudo[3293]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:28 volumio-frank sudo[3254]: pam_unix(sudo:session): session closed for user root Apr 20 16:37:28 volumio-frank volumio[1025]: error: MPD error: The expression evaluated to a falsy value: Apr 20 16:37:28 volumio-frank volumio[1025]: assert.ok(self.idling) Apr 20 16:37:28 volumio-frank volumio[1025]: error: The expression evaluated to a falsy value: Apr 20 16:37:28 volumio-frank volumio[1025]: assert.ok(self.idling) Apr 20 16:37:28 volumio-frank volumio[1025]: error: MPD error: The expression evaluated to a falsy value: Apr 20 16:37:28 volumio-frank volumio[1025]: assert.ok(self.idling) Apr 20 16:37:28 volumio-frank volumio[1025]: error: The expression evaluated to a falsy value: Apr 20 16:37:28 volumio-frank volumio[1025]: assert.ok(self.idling) Apr 20 16:37:28 volumio-frank volumio[1025]: error: updateQueue error: null Apr 20 16:37:32 volumio-frank volumio[1025]: info: Checking Spotify Web API Apr 20 16:37:32 volumio-frank volumio[1025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 16:37:32 volumio-frank volumio[1025]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 20 16:37:32 volumio-frank volumio[1025]: at healthCheck (dgram.js:897:11) Apr 20 16:37:32 volumio-frank volumio[1025]: at Socket.send (dgram.js:622:3) Apr 20 16:37:32 volumio-frank volumio[1025]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Apr 20 16:37:32 volumio-frank volumio[1025]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Apr 20 16:37:32 volumio-frank volumio[1025]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Apr 20 16:37:32 volumio-frank volumio[1025]: at listOnTimeout (internal/timers.js:554:17) Apr 20 16:37:32 volumio-frank volumio[1025]: at processTimers (internal/timers.js:497:7) { Apr 20 16:37:32 volumio-frank volumio[1025]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Apr 20 16:37:32 volumio-frank volumio[1025]: } Apr 20 16:37:32 volumio-frank volumio[1025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 16:37:33 volumio-frank sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 16:36 Apr 20 16:37:33 volumio-frank sudo[3307]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"