11-21-2024, 08:58 PM
@sdbinsf, Below is a sample log showing startup and client connect. The tokens and large amount of frame data has been omitted.
Code:
# Librespot Startup
[2024-11-21T20:34:32Z INFO librespot] librespot 0.6.0 383a6f6 (Built on 2024-11-02, Build ID: E4uuQIK1, Profile: release)
[2024-11-21T20:34:32Z TRACE librespot] Command line argument(s):
[2024-11-21T20:34:32Z TRACE librespot] name "Moode9 Spotify"
[2024-11-21T20:34:32Z TRACE librespot] bitrate "320"
[2024-11-21T20:34:32Z TRACE librespot] format "S16"
[2024-11-21T20:34:32Z TRACE librespot] mixer "softvol"
[2024-11-21T20:34:32Z TRACE librespot] initial-volume "5"
[2024-11-21T20:34:32Z TRACE librespot] volume-ctrl "log"
[2024-11-21T20:34:32Z TRACE librespot] volume-range "60"
[2024-11-21T20:34:32Z TRACE librespot] cache "/var/local/www/spotify_cache"
[2024-11-21T20:34:32Z TRACE librespot] disable-audio-cache
[2024-11-21T20:34:32Z TRACE librespot] backend "alsa"
[2024-11-21T20:34:32Z TRACE librespot] device "_audioout"
[2024-11-21T20:34:32Z TRACE librespot] onevent "/var/local/www/commandw/spotevent.sh"
[2024-11-21T20:34:32Z TRACE librespot] v
[2024-11-21T20:34:32Z TRACE librespot] Using cached credentials.
[2024-11-21T20:34:32Z DEBUG librespot_core::session] new Session
[2024-11-21T20:34:32Z DEBUG librespot_discovery::server] Zeroconf server listening on 0.0.0.0:38891
[2024-11-21T20:34:32Z INFO librespot_playback::mixer::softmixer] Mixing with softvol and volume control: Log(60.0)
[2024-11-21T20:34:32Z DEBUG librespot_playback::player] new Player [0]
[2024-11-21T20:34:32Z DEBUG librespot_connect::spirc] new Spirc[0]
[2024-11-21T20:34:32Z INFO librespot_playback::convert] Converting with ditherer: tpdf
[2024-11-21T20:34:32Z DEBUG librespot::component] new MercuryManager
[2024-11-21T20:34:32Z INFO librespot_playback::audio_backend::alsa] Using AlsaSink with format: S16
[2024-11-21T20:34:32Z DEBUG librespot::component] new SpClient
[2024-11-21T20:34:32Z DEBUG librespot_core::spclient] Client token unavailable or expired, requesting new token.
[2024-11-21T20:34:32Z DEBUG librespot_core::http_client] Requesting https://clienttoken.spotify.com/v1/clienttoken
[2024-11-21T20:34:32Z DEBUG librespot_playback::player] command=AddEventSender
[2024-11-21T20:34:32Z DEBUG librespot_core::spclient] Received a granted token
[2024-11-21T20:34:32Z TRACE librespot_core::spclient] Got client token: GrantedTokenResponse { token: "xxxxxxxxxxxxxxxxxxxx/xxxxxxxxxxxxxxxxxxxx", expires_after_seconds: 1216800, refresh_after_seconds: 1209600, domains: [TokenDomain { domain: "spotify.com", special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }, TokenDomain { domain: "spotify.net", special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }], special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }
[2024-11-21T20:34:32Z DEBUG librespot::component] new ApResolver
[2024-11-21T20:34:32Z DEBUG librespot_core::http_client] Requesting https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient
[2024-11-21T20:34:32Z INFO librespot_core::session] Connecting to AP "ap-gue1.spotify.com:4070"
[2024-11-21T20:34:32Z DEBUG librespot_core::connection] Authenticating with AP using AUTHENTICATION_STORED_SPOTIFY_CREDENTIALS
[2024-11-21T20:34:32Z INFO librespot_core::session] Authenticated as 'xxxxxxxxxxxxxxxxxxxx' !
[2024-11-21T20:34:32Z DEBUG librespot_connect::spirc] canonical_username: xxxxxxxxxxxxxxxxxxxx
[2024-11-21T20:34:32Z DEBUG librespot_playback::mixer::mappings] Input volume 3276 mapped to: 0.14%
[2024-11-21T20:34:32Z DEBUG librespot_playback::player] command=AddEventSender
[2024-11-21T20:34:32Z DEBUG librespot_core::mercury] listening to uri=spotify:user:attributes:mutated
[2024-11-21T20:34:32Z DEBUG librespot_core::mercury] listening to uri=hm://pusher/v1/connections/
[2024-11-21T20:34:32Z DEBUG librespot_core::mercury] listening to uri=hm://remote/user/
[2024-11-21T20:34:32Z DEBUG librespot_core::mercury] listening to uri=spotify:user:attributes:update
[2024-11-21T20:34:32Z TRACE librespot_core::session] Received Ping
[2024-11-21T20:34:32Z TRACE librespot_core::session] keep-alive state: PendingPong, timeout in 60.0
[2024-11-21T20:34:32Z DEBUG librespot_core::session] Session strong=4 weak=5
[2024-11-21T20:34:32Z INFO librespot_core::session] Country: "US"
[2024-11-21T20:34:33Z TRACE librespot_core::session] Received product info: {
"fb-info-confirmation": "control",
"instant-search-expand-sidebar": "0",
"prefetch-strategy": "18",
"audiobook-onboarding-dismissed": "0",
"capping-bar-threshold": "3601",
"head-files-url": "http://heads-fa.spotify.com/head/{file_id}",
"expiry": "1",
"remote-control": "0",
"ab-mobile-startpage": "0",
"offline": "1",
"ad-formats-video-takeover": "0",
"ab-collection-bookmark-model": "1",
"ad-session-persistence": "1",
"buffering-strategy": "2",
"ab-play-history": "0",
"is_email_verified": "0",
"video-keyframe-url": "http://keyframes-fa.cdn.spotify.com/keyframes/v1/sources/{source_id}/keyframe/heights/{height}/timestamps/{timestamp_ms}.jpg",
"libspotify": "1",
"browse-overview-enabled": "1",
"prefetch-window-max": "2",
"head-files": "1",
"network-operator-premium-activation": "1",
"ab-new-share-flow": "0",
"use-pl3": "0",
"ab-ad-player-targeting": "1",
"image-url": "https://i.scdn.co/image/{file_id}",
"enable-crossfade": "1",
"local-files-import": "0",
"ad-formats-preroll-video": "0",
"collection": "1",
"ab-moments-experience": "0",
"ab-mobile-running-onlymanualmode": "only-manual",
"catalogue": "premium",
"sidebar-navigation-enabled": "0",
"player-license": "premium",
"shuffle": "0",
"storage-size-config": "10240,90,500,3",
"ab-test-group": "342",
"mobile-login": "1",
"streaming": "1",
"shows-collection": "0",
"restrict-playlist-collaboration": "0",
"publish-activity": "0",
"ab-collection-union": "1",
"fb-grant-permission-local-render": "0",
"ab_recently_played_feature_time_filter_threshold": "com.spotify.gaia=30,driving-mode=120,spotify%3Ainternal%3Astartpage=30",
"audio-preview-url-template": "https://p.scdn.co/mp3-preview/{id}",
"video-device-blacklisted": "0",
"metadata-link-lookup-modes": "0",
"license-acceptance-grace-days": "30",
"employee-free-opt-in": "0",
"instant-search": "0",
"video-wifi-initial-bitrate": "800000",
"prefetch-keys": "1",
"ab-android-push-notifications": "1",
"enable-autostart": "1",
"user-profile-show-invitation-codes": "0",
"filter-explicit-content": "0",
"high-bitrate": "1",
"ab-collection-offline-mode": "0",
"on-demand": "1",
"pause-after": "0",
"playlist-annotations-markup": "0",
"ab-desktop-hide-follow": "0",
"name": "Spotify Premium",
"shuffle-algorithm": "1",
"type": "premium",
"explicit-content": "1",
"buffering-strategy-parameters": "0.8:0.2:0.0:0.0:0.0:0.0:1.0:10:10:2000:10000:10485760",
"send-email": "1",
"video-initial-bitrate": "200000",
"financial-product": "pr:premium,tc:0,rt:v2_US_2024-q3globalcampaign_winback-3m-for-1-3m_11.99_USD_default",
"ab-mobile-discover": "0",
"ap-resolve-pods": "0",
"ad-persist-reward-time": "0",
"audiobook-onboarding-completed": "0",
"mobile": "1",
"ab-watch-now": "0",
"ab-mobile-running-tempo-detection": "Control",
"arsenal_country": "1",
"video-cdn-sampling": "1",
"unrestricted": "1",
"ads": "0",
"app-developer": "0",
"allow-override-internal-prefs": "0",
"india-experience": "0",
"publish-playlist": "0",
"track-cap": "0",
"payments-locked-state": "0",
"enable-gapless": "1",
"playlist-folders": "1",
"mobile-browse": "0",
"preferred-locale": "en-us",
"shows-collection-jam": "0",
"widevine-license-url": "https://spclient.wg.spotify.com/widevine-license/v1/video/license",
"ab-browse-music-tuesday": "1",
"ab-collection-followed-artists-only": "0",
"key-caching-max-count": "10000",
"nft-disabled": "1",
"ugc-abuse-report-url": "https://support.spotify.com/abuse/?uri={uri}",
"head-file-caching": "1",
"video-manifest-url": "https://spclient.wg.spotify.com/manifests/v6/{type}/sources/{source_id}",
"payments-initial-campaign": "default",
"incognito_mode_timeout": "21600",
"last-premium-activation-date": "2024-09-05T11:19:26Z",
"radio": "1",
"key-caching-max-offline-seconds": "1800",
"enable-annotations-read": "0",
"mobile-payment": "0",
"use-fb-publish-backend": "2",
"ab-mobile-social-feed": "1",
"ab-collection-hide-unavailable-albums": "0",
"use-playlist-uris": "0",
"ugc-abuse-report": "0",
}
[2024-11-21T20:34:33Z TRACE librespot_connect::spirc] Received connection ID update: "xxxxxxxxxxxxxxxxxxxx"
[2024-11-21T20:34:33Z DEBUG librespot::component] new Login5Manager
[2024-11-21T20:34:33Z DEBUG librespot_core::http_client] Requesting https://login5.spotify.com/v3/login
[2024-11-21T20:34:33Z TRACE librespot_core::login5] Got auth token: Token { access_token: "xxxxxxxxxxxxxxxxxxxx", expires_in: 3600s, token_type: "Bearer", scopes: [], timestamp: Instant { tv_sec: 2551, tv_nsec: 819995172 } }
[2024-11-21T20:34:33Z DEBUG librespot_connect::spirc] successfully pre-acquire access_token and client_token
[2024-11-21T20:34:33Z TRACE librespot_connect::spirc] Received update frame: Frame {
.
. Frame data
.
# Client Connected
[2024-11-21T20:36:09Z TRACE librespot_connect::spirc] Sending status to server: [kPlayStatusPause]
[2024-11-21T20:36:32Z TRACE librespot_core::session] Received Ping
[2024-11-21T20:36:32Z TRACE librespot_core::session] keep-alive state: PendingPong, timeout in 60.0
[2024-11-21T20:36:32Z DEBUG librespot_core::session] Session strong=4 weak=7
[2024-11-21T20:37:32Z TRACE librespot_core::session] Sending Pong
[2024-11-21T20:37:32Z TRACE librespot_core::session] keep-alive state: ExpectingPongAck, timeout in 20.0
[2024-11-21T20:37:33Z TRACE librespot_core::session] Received PongAck
[2024-11-21T20:37:33Z TRACE librespot_core::session] keep-alive state: ExpectingPing, timeout in 80.0
[2024-11-21T20:38:32Z TRACE librespot_core::session] Received Ping
[2024-11-21T20:38:32Z TRACE librespot_core::session] keep-alive state: PendingPong, timeout in 60.0
[2024-11-21T20:38:32Z DEBUG librespot_core::session] Session strong=4 weak=7
[2024-11-21T20:39:32Z TRACE librespot_core::session] Sending Pong
[2024-11-21T20:39:32Z TRACE librespot_core::session] keep-alive state: ExpectingPongAck, timeout in 20.0
[2024-11-21T20:39:33Z TRACE librespot_core::session] Received PongAck
[2024-11-21T20:39:33Z TRACE librespot_core::session] keep-alive state: ExpectingPing, timeout in 80.0