spotupnp play´s only first song of the playlist #62
Description
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