-- Logs begin at Mon 2023-12-04 08:27:43 UTC, end at Fri 2024-07-05 09:42:17 UTC. -- Jul 05 09:41:03 volumio-attic volumio[989]: info: Starting Uninstall of plugin music_service - spop Jul 05 09:41:03 volumio-attic volumio[989]: info: Uninstalling plugin spop Jul 05 09:41:03 volumio-attic volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 05 09:41:03 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:41:03 volumio-attic volumio[989]: [SpotifyConnect] Closing metadata listener Jul 05 09:41:03 volumio-attic volumio[989]: info: Disabling plugin spop Jul 05 09:41:03 volumio-attic volumio[989]: info: Checking if uninstall.sh is present Jul 05 09:41:03 volumio-attic volumio[989]: info: Executing uninstall.sh Jul 05 09:41:03 volumio-attic sudo[2611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Jul 05 09:41:03 volumio-attic sudo[2611]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:03 volumio-attic systemd[1]: Stopping Volspotconnect2 Daemon... Jul 05 09:41:03 volumio-attic systemd[1]: Stopped Volspotconnect2 Daemon. Jul 05 09:41:03 volumio-attic sudo[2611]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:03 volumio-attic volumio[989]: [SpotifyConnect] Vollibrespot Daemon service stoped! Jul 05 09:41:03 volumio-attic sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Jul 05 09:41:03 volumio-attic sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:03 volumio-attic sudo[2618]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:03 volumio-attic volumio[989]: info: Uninstall script completed Jul 05 09:41:03 volumio-attic volumio[989]: info: Removing plugin spop from configuration Jul 05 09:41:04 volumio-attic volumio[989]: info: Successfully removed spop configuration files Jul 05 09:41:04 volumio-attic volumio[989]: info: Plugin folders cleanup Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category audio_interface Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category miscellanea Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category music_service Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category plugins.json Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category system_controller Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category user_interface Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning into folder /data/plugins/ Jul 05 09:41:04 volumio-attic volumio[989]: info: Scanning category music_service Jul 05 09:41:04 volumio-attic volumio[989]: info: Cleaning folder for spop Jul 05 09:41:05 volumio-attic volumio[989]: info: Plugin folders cleanup completed Jul 05 09:41:05 volumio-attic volumio[989]: info: [{"prettyName":"Podcast","name":"podcast","category":"music_service","version":"0.3.0","icon":"fa-podcast","enabled":true,"active":true},{"prettyName":"Youtube Playback","name":"youtube","category":"music_service","version":"0.1.0","icon":"fa-youtube","enabled":true,"active":true}] Jul 05 09:41:17 volumio-attic volumio[989]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jul 05 09:41:18 volumio-attic volumio[989]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jul 05 09:41:18 volumio-attic volumio[989]: info: Folder /tmp/plugins removed Jul 05 09:41:18 volumio-attic volumio[989]: info: Checking if plugin already exists Jul 05 09:41:18 volumio-attic volumio[989]: info: Rename folder Jul 05 09:41:19 volumio-attic volumio[989]: info: Folder /tmp/downloaded_plugin.zip removed Jul 05 09:41:19 volumio-attic volumio[989]: info: Move to category Jul 05 09:41:25 volumio-attic volumio[989]: info: Checking if install.sh is present Jul 05 09:41:25 volumio-attic volumio[989]: info: Executing install.sh Jul 05 09:41:25 volumio-attic sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/volspotconnect2/install.sh Jul 05 09:41:25 volumio-attic sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:25 volumio-attic volumio[989]: info: Launching a bash shell Jul 05 09:41:25 volumio-attic volumio[989]: info: Installing volspotconnect2 dependencies Jul 05 09:41:25 volumio-attic volumio[989]: info: Detected cpu architecture as armv7l Jul 05 09:41:26 volumio-attic volumio[989]: info: Latest version: v0.2.5 Requested version: v0.2.2 Jul 05 09:41:26 volumio-attic volumio[989]: info: Supported device (arch = armv7l), downloading required packages for vollibrespot v0.2.2 Jul 05 09:41:26 volumio-attic volumio[989]: info: Downloading file Jul 05 09:41:28 volumio-attic volumio[989]: info: Extracting... Jul 05 09:41:28 volumio-attic volumio[989]: info: -rw-r--r-- 1 root root 4289193 Jul 5 09:41 vollibrespot-armv7l.tar.xz Jul 05 09:41:28 volumio-attic volumio[989]: info: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jul 05 09:41:28 volumio-attic sudo[2715]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/tar -xvf volspotconnect2.service.tar -C / Jul 05 09:41:28 volumio-attic sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:28 volumio-attic volumio[989]: info: etc/systemd/system/volspotconnect2.service Jul 05 09:41:28 volumio-attic sudo[2715]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:28 volumio-attic sudo[2723]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/music_service/volspotconnect2/onstart1.sh Jul 05 09:41:28 volumio-attic sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:28 volumio-attic sudo[2723]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:28 volumio-attic sudo[2680]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:28 volumio-attic volumio[989]: info: volspotconnect2 installed Jul 05 09:41:28 volumio-attic volumio[989]: Plugin install end detected on script Jul 05 09:41:28 volumio-attic volumio[989]: info: Install script completed Jul 05 09:41:28 volumio-attic volumio[989]: info: Adding reference to registry Jul 05 09:41:28 volumio-attic volumio[989]: info: Done installing plugin. Jul 05 09:41:28 volumio-attic volumio[989]: info: Folder /tmp/plugins removed Jul 05 09:41:28 volumio-attic volumio[989]: info: Folder /tmp/downloaded_plugin.zip removed Jul 05 09:41:28 volumio-attic volumio[989]: info: Folder /data/temp removed Jul 05 09:41:32 volumio-attic volumio[989]: info: Enabling plugin volspotconnect2 Jul 05 09:41:32 volumio-attic volumio[989]: info: Loading plugin "volspotconnect2"... Jul 05 09:41:32 volumio-attic volumio[989]: info: PLUGIN START: volspotconnect2 Jul 05 09:41:32 volumio-attic volumio[989]: [SpotifyConnect] Creating VLS config file Jul 05 09:41:32 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:41:32 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:41:32 volumio-attic volumio[989]: [SpotifyConnect] Starting metadata listener Jul 05 09:41:32 volumio-attic sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 05 09:41:32 volumio-attic sudo[2742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:32 volumio-attic systemd[1]: Starting Volspotconnect2 Daemon... Jul 05 09:41:32 volumio-attic systemd[1]: Started Volspotconnect2 Daemon. Jul 05 09:41:32 volumio-attic sudo[2742]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:32 volumio-attic volumio[2747]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jul 05 09:41:32 volumio-attic volumio[2747]: Reading Config from "volspotify.toml" Jul 05 09:41:32 volumio-attic volumio[2747]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Jul 05 09:41:32 volumio-attic volumio[989]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 05 09:41:32 volumio-attic volumio[989]: [Metrics] SpotifyConnect: 0s 76.68ms Jul 05 09:41:32 volumio-attic volumio[989]: info: Done. Jul 05 09:41:32 volumio-attic volumio[2747]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jul 05 09:41:41 volumio-attic volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:41:41 volumio-attic volumio[989]: [SpotifyConnect] config : toggling initvol/volume_ctrl Jul 05 09:41:50 volumio-attic volumio[989]: info: CALLMETHOD: music_service volspotconnect2 saveVolspotconnectAccount [object Object] Jul 05 09:41:50 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: volspotconnect2 , saveVolspotconnectAccount Jul 05 09:41:50 volumio-attic volumio[989]: [SpotifyConnect] Creating VLS config file Jul 05 09:41:50 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:41:50 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:41:50 volumio-attic volumio[989]: [SpotifyConnect] Restarting Vollibrespot Daemon Jul 05 09:41:50 volumio-attic sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Jul 05 09:41:50 volumio-attic sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:41:50 volumio-attic systemd[1]: Stopping Volspotconnect2 Daemon... Jul 05 09:41:50 volumio-attic systemd[1]: Starting Volspotconnect2 Daemon... Jul 05 09:41:50 volumio-attic systemd[1]: Started Volspotconnect2 Daemon. Jul 05 09:41:50 volumio-attic sudo[2808]: pam_unix(sudo:session): session closed for user root Jul 05 09:41:50 volumio-attic volumio[2814]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jul 05 09:41:50 volumio-attic volumio[2814]: Reading Config from "volspotify.toml" Jul 05 09:41:50 volumio-attic volumio[2814]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Jul 05 09:41:50 volumio-attic volumio[989]: [SpotifyConnect] Vollibrespot Daemon service restarted! Jul 05 09:41:50 volumio-attic volumio[2814]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jul 05 09:42:02 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 09:42:02 volumio-attic volumio[989]: info: CURURI: playlists Jul 05 09:42:02 volumio-attic volumio[989]: info: Listing playlists Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:42:07 volumio-attic volumio[989]: info: Discovery: Getting this device information Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::volumioGetState Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 05 09:42:07 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConf Jul 05 09:42:08 volumio-attic volumio[989]: info: CoreCommandRouter::volumioVolatilePlay Jul 05 09:42:08 volumio-attic volumio[989]: info: CoreStateMachine::volatilePlay Jul 05 09:42:08 volumio-attic volumio[989]: info: WARNING: No play method for volatile plugin spop Jul 05 09:42:09 volumio-attic volumio[989]: info: CoreCommandRouter::volumioVolatilePlay Jul 05 09:42:09 volumio-attic volumio[989]: info: CoreStateMachine::volatilePlay Jul 05 09:42:09 volumio-attic volumio[989]: info: WARNING: No play method for volatile plugin spop Jul 05 09:42:11 volumio-attic volumio[989]: info: CoreCommandRouter::volumioVolatilePlay Jul 05 09:42:11 volumio-attic volumio[989]: info: CoreStateMachine::volatilePlay Jul 05 09:42:11 volumio-attic volumio[989]: info: WARNING: No play method for volatile plugin spop Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Authenticated as "hpereirinha" ! Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Setting up new mixer: card:hw:2 mixer:Master index:0 Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 248 (MilliBel(0)[dB]) HW: false Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Switching to linear volume mapping, control range: MilliBel(0) Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Using alsa sink Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Metadata pipe established Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Country: "PT" Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] 0 Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreStateMachine::pushState Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreCommandRouter::volumioPushState Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Fetching autoplay context uri Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Event: SessionActive { became_active_at: 1720172536893 } Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : SessionActive! Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] A connect session has begun Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 116608883045694897011132840270040114066, audio_type: Track } } Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 116608883045694897011132840270040114066, audio_type: Track } } Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] Device palyback is active! Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreCommandRouter::volumioGetState Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] Currently active: spop Jul 05 09:42:16 volumio-attic volumio[989]: [SpotifyConnect] Stopping currently active service Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreCommandRouter::volumioStop Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreStateMachine::stop Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreStateMachine::serviceStop Jul 05 09:42:16 volumio-attic volumio[989]: info: CoreCommandRouter::serviceStop Jul 05 09:42:16 volumio-attic volumio[989]: error: WARNING: No stop method for service spop Jul 05 09:42:16 volumio-attic volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:42:16 volumio-attic volumio[989]: TypeError: Cannot read property 'then' of undefined Jul 05 09:42:16 volumio-attic volumio[989]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Jul 05 09:42:16 volumio-attic volumio[989]: at emitNone (events.js:106:13) Jul 05 09:42:16 volumio-attic volumio[989]: at SpotConnEvents.emit (events.js:208:7) Jul 05 09:42:16 volumio-attic volumio[989]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Jul 05 09:42:16 volumio-attic volumio[989]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Jul 05 09:42:16 volumio-attic volumio[989]: at emitTwo (events.js:126:13) Jul 05 09:42:16 volumio-attic volumio[989]: at Socket.emit (events.js:214:7) Jul 05 09:42:16 volumio-attic volumio[989]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jul 05 09:42:16 volumio-attic volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:1nUHTO5Xz3ysd9cMjx09oG"> Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Resolving uri "spotify:station:playlist:1nUHTO5Xz3ysd9cMjx09oG" Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBx72gUjtcM3L-ddTt378sdWQiGXa8TtGOEirrYh8k6_pbHvbeUs5nDc28kHC2BUblC3UjhifGViwCE7WGoR4CSv0d4osmKhyx5eW3w5vcDAR90m6Bk3QcgMnBb2R3fbejXIEpl5D5jRyyZA7veUo8qY58XlQ0VJJnIK4VsohwQpKKQCmGGqC3znBIm83po_8CtAao", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Jul 05 09:42:16 volumio-attic volumio[2814]: [Vollibrespot] : Loading with Spotify URI Jul 05 09:42:17 volumio-attic volumio[2814]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:1nUHTO5Xz3ysd9cMjx09oG"> Jul 05 09:42:17 volumio-attic volumio[2814]: [Vollibrespot] : (194087 ms) loaded Jul 05 09:42:17 volumio-attic volumio[2814]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 116608883045694897011132840270040114066, audio_type: Track } } Jul 05 09:42:17 volumio-attic sudo[2890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 09:41 Jul 05 09:42:17 volumio-attic sudo[2890]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"