-- 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"