Skip to content

spotupnp play´s only first song of the playlist #62

Description

@philipp-andreas

Hello Philippe,
i have issues with the new version of spotconnect.
only the first song of my playlist will be played and loops.
tested on debian/raspian/windows11

lg Andreas

log windows 11:
[08:37:20.751] main:1532 Starting spotupnp version: v0.20.1 (Dec 24 2025 @ 23:41:38)
[08:37:20.782] Start:1227 Binding to iface Ethernet@10.0.0.67:0
[08:37:20.782] Start:1241 Binding to 10.0.0.67:49152
[08:37:21.337] AddMRDevice:1153 [08A89950]: adding renderer (SqueezeBox Boom 1) with mac BBBBCxxxx
[08:37:21.348] MasterHandler:745 [08A89950]: subscribe success
[08:37:21.353] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:21.356] I spotify.cpp:539: ZeroConf mode (port 58290)
[08:37:21.374] I MDNSService.cpp:79: mDNS on interface 10.0.0.67
[08:37:21.442] AddMRDevice:1153 [08A8C1B8]: adding renderer (Arbeitszimmer) with mac BBBBABxxxx
[08:37:21.451] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:21.453] I spotify.cpp:539: ZeroConf mode (port 58296)
[08:37:21.454] MasterHandler:745 [08A8C1B8]: subscribe success
[08:37:21.555] ProcessEvent:476 [08A89950]: UPnP Volume local change 52:-1 (master)
[08:37:21.656] ProcessEvent:476 [08A8C1B8]: UPnP Volume local change 18:-1 (master)
[08:37:21.894] AddMRDevice:1153 [08A8EA20]: adding renderer (BadUG) with mac BBBBF4xxxxxx
[08:37:21.905] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:21.907] I spotify.cpp:539: ZeroConf mode (port 58301)
[08:37:21.908] MasterHandler:745 [08A8EA20]: subscribe success
[08:37:22.117] ProcessEvent:476 [08A8EA20]: UPnP Volume local change 20:-1 (master)
[08:37:22.532] AddMRDevice:1153 [08A91288]: adding renderer (SqueezeBox Boom 2) with mac BBBB49xxxxx
[08:37:22.542] MasterHandler:745 [08A91288]: subscribe success
[08:37:22.544] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:22.545] I spotify.cpp:539: ZeroConf mode (port 58305)
[08:37:22.745] ProcessEvent:476 [08A91288]: UPnP Volume local change 8:-1 (master)
[08:37:22.822] AddMRDevice:1153 [08A93AF0]: adding renderer (K├╝che) with mac BBBB974xxxxx
[08:37:22.835] MasterHandler:745 [08A93AF0]: subscribe success
[08:37:22.838] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:22.840] I spotify.cpp:539: ZeroConf mode (port 58309)
[08:37:22.906] AddMRDevice:1153 [08A96358]: adding renderer (deconz) with mac BBBBFxxxx
[08:37:22.914] I BellHTTPServer.cpp:194: Server listening on port 0
[08:37:22.915] MasterHandler:745 [08A96358]: subscribe success
[08:37:22.917] I spotify.cpp:539: ZeroConf mode (port 58313)
[08:37:23.042] ProcessEvent:476 [08A93AF0]: UPnP Volume local change 21:-1 (master)
[08:37:23.123] ProcessEvent:476 [08A96358]: UPnP Volume local change 51:-1 (master)
[08:37:58.639] I spotify.cpp:582: Spotify client launched for Arbeitszimmer+
[08:37:58.777] D Session.cpp:67: Connecting with AP ap-gew4.spotify.com:4070
[08:37:58.821] D PlainConnection.cpp:101: Connected to spotify server
[08:37:58.860] I Session.cpp:43: Received APHello response
[08:37:58.869] D Session.cpp:48: Received shannon keys
[08:37:58.920] D Session.cpp:87: Authorization successful
[08:37:58.922] I MercurySession.cpp:42: Received packet, command: 4
[08:37:58.923] D TimeProvider.cpp:15: Time synced with spotify servers
[08:37:58.925] I MercurySession.cpp:42: Received packet, command: 2
[08:37:58.927] [08:37:58.927] D I MercurySession.cppMercurySession.cpp:42: :252: Executing Mercury Request, type SUB
Received packet, command: 118
[08:37:58.929] I MercurySession.cpp:42: Received packet, command: 27
[08:37:58.930] [08:37:58.930] I D MercurySession.cppMercurySession.cpp:42: :153: Received country code AT
Received packet, command: 80
[08:37:58.932] I MercurySession.cpp:42: Received packet, command: 31
[08:37:58.933] I MercurySession.cpp:42: Received packet, command: 105
[08:37:58.951] I MercurySession.cpp:42: Received packet, command: 181
[08:37:59.021] I MercurySession.cpp:42: Received packet, command: 74
[08:37:59.024] I MercurySession.cpp:42: Received packet, command: 179
[08:37:59.027] D MercurySession.cpp:174: Received mercury packet
[08:37:59.024] I AccessKeyFetcher.cpp:72: Access token expired, fetching new one...
[08:37:59.029] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:37:59.032] D SpircHandler.cpp:61: Sent kMessageTypeHello!
[08:37:59.105] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59.107] I MercurySession.cpp:42: Received packet, command: 181
[08:37:59.107] D MercurySession.cpp:174: Received mercury packet
[08:37:59.110] D SpircHandler.cpp:69: Received subscription response
[08:37:59.110] D SpircHandler.cpp:132: Notify frame
[08:37:59.168] I AccessKeyFetcher.cpp:94: Access token sucessfully fetched
[08:37:59.287] I MercurySession.cpp:42: Received packet, command: 181
[08:37:59.290] D SpircHandler.cpp:69: Received subscription response
[08:37:59.295] D SpircHandler.cpp:179: Load frame 100!
[08:37:59.297] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:37:59.307] I TrackPlayer.cpp:98: Resetting state
[08:37:59.359] D MercurySession.cpp:252: Executing Mercury Request, type GET
[08:37:59.367] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59.372] D MercurySession.cpp:174: Received mercury packet
[08:37:59.426] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59.429] D [08:37:59.429] I MercurySession.cpp:42: Received packet, command: 181MercurySession.cpp:174: Received mercury packet

[08:37:59.432] I TrackQueue.cpp:162: Track name: Nightcall
[08:37:59.433] I TrackQueue.cpp:163: Track duration: 258413
[08:37:59.434] D TrackQueue.cpp:166: trackInfo.restriction.size() = 1
[08:37:59.435] D TrackQueue.cpp:216: File format: 2
[08:37:59.437] D TrackQueue.cpp:216: File format: 1
[08:37:59.469] I MercurySession.cpp:42: Received packet, command: 181
[08:37:59.505] I MercurySession.cpp:42: Received packet, command: 13
[08:37:59.517] I TrackQueue.cpp:256: Got audio key
[08:37:59.519] I TrackQueue.cpp:279: Received access key, fetching CDN URL...
[08:37:59.673] I TrackQueue.cpp:305: Received CDN URL, https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080279_j86uSAWrd9y4Qzkez5-lhbKuDxF8bcCCZQY6-Y4M1F0=
[08:37:59.674] I TrackPlayer.cpp:171: Got track ID=e37f529ae9c5ee38bd92ebaf5cf421f3af94870e
[08:37:59.675] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080279_j86uSAWrd9y4Qzkez5-lhbKuDxF8bcCCZQY6-Y4M1F0=
[08:37:59.767] D MercurySession.cpp:252: Executing Mercury Request, type GET
[08:37:59.769] I MercurySession.cpp:42: Received packet, command: 181
[08:37:59.777] I CDNAudioFile.cpp:70: Header and footer bytes received
[08:37:59.779] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:37:59.766] shadowRequest:329 [08A8C1B8]: Stop
[08:37:59.781] I spotify.cpp:301: new track will start at 272929
[08:37:59.782] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:37:59.785] I spotify.cpp:323: Play
[08:37:59.814] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59.815] D MercurySession.cpp:174: Received mercury packet
[08:37:59.816] I TrackQueue.cpp:162: Track name: Nightcall
[08:37:59.816] I TrackQueue.cpp:163: Track duration: 258413
[08:37:59.818] D TrackQueue.cpp:166: trackInfo.restriction.size() = 1
[08:37:59.819] D TrackQueue.cpp:216: File format: 2
[08:37:59.819] D TrackQueue.cpp:216: File format: 1
[08:37:59.845] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59.846] D MercurySession.cpp:174: Received mercury packet
[08:37:59.873] I TrackPlayer.cpp:206: Playing
[08:37:59.877] I spotify.cpp:180: trackUniqueId update => e37f529ae9c5ee38bd92ebaf5cf421f3af94870e
[08:37:59.878] I spotify.cpp:249: new track id 1dae1a13710f494cb4071715b61865d5 =>
[08:37:59.881] I HTTPstreamer.cpp:192: Bound to port 58336
[08:37:59.882] I spotify.cpp:257: loading with id bbbb62a7xxx_0
[08:37:59.882] shadowRequest:346 [08A8C1B8]: spotify LOAD request
[08:37:59.882] AVTSetURI:69 [08A8C1B8]: uPNP setURI http://10.0.0.67:58336/spotupnp.flac?id=bbbb62a7xxx_0 (cookie 00000000)
[08:37:59.882] shadowRequest:360 [08A8C1B8]: spotify play request
[08:37:59.882] AVTPlay:123 [08A8C1B8]: uPNP play (cookie 00000001)
[08:37:59.928] I MercurySession.cpp:42: Received packet, command: 178
[08:37:59[08:37:59.929] I MercurySession.cpp.929] :42: Received packet, command: 13
D MercurySession.cpp:174: Received mercury packet
[08:37:59.930] I HTTPstreamer.cpp[08:37:59.:562: got HTTP connection 3052
930] I [08:37:59.934] I HTTPstreamer.cppTrackQueue.cpp:256: :280: HTTP received =>
GET /spotupnp.flac?id=bbbb62a7xxxx_0 HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: 10.0.0.67:58336
Range: bytes=0-
User-Agent: iTunes/4.7.1 (Linux; N; Debian; x86_64-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Lyrion Music Server/9.0.3/1759466965
Icy-MetaData: 1

Got audio key
[08:37:59.937] I TrackQueue.cpp:279: Received access key, fetching CDN URL...
[08:37:59.937] I HTTPstreamer.cpp:429: HTTP response =>
HTTP/1.0 200 OK
Server: spot-connect
Accept-Ranges: bytes
Content-Type: audio/flac
Connection: close

[08:38:00.046] I TrackQueue.cpp:305: Received CDN URL, https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080280_hKvNR6Yaqi2vUkts2QEeJlCif30D0bQmrHxj9mdXiu4=
[08:38:00.048] D MercurySession.cpp:252: Executing Mercury Request, type GET
[08:38:00.082] I MercurySession.cpp:42: Received packet, command: 178
[08:38:00.084] D MercurySession.cpp:174: Received mercury packet
[08:38:00.085] I TrackQueue.cpp:162: Track name: Nightcall
[08:38:00.086] I TrackQueue.cpp:163: Track duration: 258413
[08:38:00.087] D TrackQueue.cpp:166: trackInfo.restriction.size() = 1
[08:38:00.089] D TrackQueue.cpp:216: File format: 2
[08:38:00.090] D TrackQueue.cpp:216: File format: 1
[08:38:00.125] I MercurySession.cpp:42: Received packet, command: 13
[08:38:00.126] I TrackQueue.cpp:256: Got audio key
[08:38:00.127] I TrackQueue.cpp:279: Received access key, fetching CDN URL...
[08:38:00.238] I TrackQueue.cpp:305: Received CDN URL, https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080280_hKvNR6Yaqi2vUkts2QEeJlCif30D0bQmrHxj9mdXiu4=
[08:38:01.516] ActionHandler:551 [08A8C1B8]: uPNP transition
[08:39:58.921] I MercurySession.cpp:42: Received packet, command: 4
[08:39:58.923] D TimeProvider.cpp:15: Time synced with spotify servers
[08:39:58.955] I MercurySession.cpp:42: Received packet, command: 74
[08:41:01.195] I TrackPlayer.cpp:224: EOF
[08:41:01.197] I TrackPlayer.cpp:255: Playing done
[08:41:01.251] I TrackPlayer.cpp:171: Got track ID=e37f529ae9c5ee38bd92ebaf5cf421f3af94870e
[08:41:01.253] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080280_hKvNR6Yaqi2vUkts2QEeJlCif30D0bQmrHxj9mdXiu4=
[08:41:01.447] I CDNAudioFile.cpp:70: Header and footer bytes received
[08:41:01.528] I TrackPlayer.cpp:206: Playing
[08:41:58.922] I MercurySession.cpp:42: Received packet, command: 4
[08:41:58.926] D TimeProvider.cpp:15: Time synced with spotify servers
[08:41:58.956] I MercurySession.cpp:42: Received packet, command: 74
[08:43:30.274] I MercurySession.cpp:42: Received packet, command: 181
[08:43:30.277] D SpircHandler.cpp:69: Received subscription response
[08:43:30.279] I TrackPlayer.cpp:98: Resetting state
[08:43:30.281] I TrackPlayer.cpp:107: Seeking...
[08:43:30.283] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:43:30.290] I spotify.cpp:363: seeking from streamer bbbb62a71xxx_0 at 235052
[08:43:30.290] shadowRequest:329 [08A8C1B8]: Stop
[08:43:30.294] AVTStop:180 [08A8C1B8]: uPNP stop (cookie 000003AC)
[08:43:30.290] I TrackPlayer.cpp:255: Playing done
[08:43:30.300] shadowRequest:346 [08A8C1B8]: spotify LOAD request
[08:43:30.302] AVTSetURI:69 [08A8C1B8]: uPNP setURI http://10.0.0.67:58336/spotupnp.flac?id=bbbb62axxxx_0 (cookie 000003AD)
[08:43:30.302] shadowRequest:360 [08A8C1B8]: spotify play request
[08:43:30.302] AVTPlay:123 [08A8C1B8]: uPNP play (cookie 000003AD)
[08:43:30.312] I HTTPstreamer.cpp:579: HTTP close 3052 (sent:0)
[08:43:30.347] I HTTPstreamer.cpp:562: got HTTP connection 4092
[08:43:30.349] I HTTPstreamer.cpp:280: HTTP received =>
GET /spotupnp.flac?id=bbbb62a7xxx_0 HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: 10.0.0.67:58336
Range: bytes=0-
User-Agent: iTunes/4.7.1 (Linux; N; Debian; x86_64-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Lyrion Music Server/9.0.3/1759466965
Icy-MetaData: 1

[08:43:30.351] I HTTPstreamer.cpp:429: HTTP response =>
HTTP/1.0 200 OK
Server: spot-connect
Accept-Ranges: bytes
Content-Type: audio/flac
Connection: close

[08:43:30.354] I MercurySession.cpp:42: Received packet, command: 178
[08:43:30.356] D MercurySession.cpp:174: Received mercury packet
[08:43:30.359] I TrackPlayer.cpp:171: Got track ID=e37f529ae9c5ee38bd92ebaf5cf421f3af94870e
[08:43:30.360] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080279_j86uSAWrd9y4Qzkez5-lhbKuDxF8bcCCZQY6-Y4M1F0=
[08:43:30.460] I CDNAudioFile.cpp:70: Header and footer bytes received
[08:43:30.647] I TrackPlayer.cpp:206: Playing
[08:43:31.611] I TrackPlayer.cpp:224: EOF
[08:43:31.611] I TrackPlayer.cpp:255: Playing done
[08:43:31.667] I TrackPlayer.cpp:171: Got track ID=e37f529ae9c5ee38bd92ebaf5cf421f3af94870e
[08:43:31.669] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-fa-tls130.spotifycdn.com/audio/e37f529ae9c5ee38bd92ebaf5cf421f3af94870e?1767080280_hKvNR6Yaqi2vUkts2QEeJlCif30D0bQmrHxj9mdXiu4=
[08:43:31.757] I CDNAudioFile.cpp:70: Header and footer bytes received
[08:43:31.834] I TrackPlayer.cpp:206: Playing
[08:43:58.920] I MercurySession.cpp:42: Received packet, command: 4
[08:43:58.926] D TimeProvider.cpp:15: Time synced with spotify servers
[08:43:58.957] I MercurySession.cpp:42: Received packet, command: 74

Activity

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Projects

    No projects

      Milestone

      No milestone

      Relationships

      None yet

      Development

      No branches or pull requests

      Issue actions