Duplicate LastFM scobbling: 6.0 beta 2

[dmbfan36_23]

I just installed 6.0 beta 2 on a new computer and I'm noticing duplicate scrobbling entries on Last.fm. Below are log entries for two songs, note: I was not hitting play multiple times within a single second.

Code: Select all

[3/30/16 6:12:34 PM EDT]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Just My Luck' for user XXX at Last.fm: Wed Mar 30 18:12:34 EDT 2016
[3/30/16 6:12:35 PM EDT]   INFO   AudioScrobblerService   Successfully registered submission for song 'Just My Luck' for user XXX at Last.fm: Wed Mar 30 18:12:34 EDT 2016
[3/30/16 6:12:51 PM EDT]   INFO   AudioScrobblerService   Successfully registered submission for song 'Just My Luck' for user XXX at Last.fm: Wed Mar 30 18:12:50 EDT 2016

....

[3/30/16 6:12:51 PM EDT]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Just Beneath The Surface' for user XXX at Last.fm: Wed Mar 30 18:12:51 EDT 2016
[3/30/16 6:12:52 PM EDT]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Just Beneath The Surface' for user XXX at Last.fm: Wed Mar 30 18:12:51 EDT 2016
[3/30/16 6:12:52 PM EDT]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Just Beneath The Surface' for user XXX at Last.fm: Wed Mar 30 18:12:51 EDT 2016
[3/30/16 6:12:53 PM EDT]   INFO   AudioScrobblerService   Successfully registered submission for song 'Just Beneath The Surface' for user XXX at Last.fm: Wed Mar 30 18:12:51 EDT 2016
[3/30/16 6:12:53 PM EDT]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Just Beneath The Surface' for user XXX at Last.fm: Wed Mar 30 18:12:51 EDT 2016

[topdownjimmy]

Yes! I'm getting this too. It seems to happen when using the web player in Safari. When using iSub on an iPhone, these dupes don't seem to happen.

Haven't tested in Chrome or Firefox.

[topdownjimmy]

Tested with Firefox 45, didn't get any duplicates. It looks like this may just be a Safari (9.1) problem.

[topdownjimmy]

This is still happening to me with 6.0 final.

[Harley_K]

I confirm dublicates in last.fm when plaing from WebUI in Opera 36.0.2130.65 also playback starts with great delay (about 10 seconds)

[5/3/16 12:39:40 PM MSK] INFO AudioScrobblerService Successfully registered now playing for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:39 MSK 2016
[5/3/16 12:39:41 PM MSK] INFO AudioScrobblerService Successfully registered now playing for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:39 MSK 2016
[5/3/16 12:39:42 PM MSK] INFO AudioScrobblerService Successfully registered submission for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:39 MSK 2016
[5/3/16 12:39:49 PM MSK] INFO TranscodeInputStream Starting transcoder: c:\subsonic\transcode\ffmpeg -i E:\Music\CD\Eisbrecher\2010 - Eisbrecher - Eiszeit (CD)\02 Eisbrecher - Eiszeit.flac -map 0:0 -b:a 320k -v 0 -f mp3 -
[5/3/16 12:39:49 PM MSK] INFO TranscodeInputStream Starting transcoder: c:\subsonic\transcode\ffmpeg -i E:\Music\CD\Eisbrecher\2010 - Eisbrecher - Eiszeit (CD)\02 Eisbrecher - Eiszeit.flac -map 0:0 -b:a 320k -v 0 -f mp3 -
[5/3/16 12:39:50 PM MSK] INFO AudioScrobblerService Successfully registered submission for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:49 MSK 2016
[5/3/16 12:39:51 PM MSK] INFO AudioScrobblerService Successfully registered now playing for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:49 MSK 2016
[5/3/16 12:39:52 PM MSK] INFO AudioScrobblerService Successfully registered now playing for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:49 MSK 2016
[5/3/16 12:39:53 PM MSK] INFO AudioScrobblerService Successfully registered submission for song 'Eiszeit' for user Harley_K at Last.fm: Tue May 03 12:39:49 MSK 2016

Chrome 50.0.2661.94 and 46.0 start playback with no delay, and makes no dublicates.

[MonsterMuffin]

I came here to post exactly this.

Every time I play a song I am getting loads of duplicates, I am using Chrome 50.0.2661.94 m and is has been doing this for a while now. I haven't tested other browsers as I didn't think this was a browser issue.

Code: Select all

[5/3/16 11:03:01 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:03:00 BST 2016
[5/3/16 11:03:02 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:03:00 BST 2016
[5/3/16 11:03:03 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:03:00 BST 2016
[5/3/16 11:03:14 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:03:13 BST 2016
[5/3/16 11:04:16 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:04:15 BST 2016
[5/3/16 11:04:16 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Paradise Lost' for user monstermuffin20 at Last.fm: Tue May 03 11:04:16 BST 2016
[5/3/16 11:06:32 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Event Horizon' for user monstermuffin20 at Last.fm: Tue May 03 11:06:31 BST 2016
[5/3/16 11:06:33 AM BST]   INFO   PodcastService   Starting scheduled Podcast refresh.
[5/3/16 11:06:33 AM BST]   INFO   PodcastService   Completed scheduled Podcast refresh.
[5/3/16 11:06:34 AM BST]   WARN   JaudiotaggerParser   Error when parsing tags in c:\music\Podcast\Above & Beyond- Group Therapy\ABGT176_374377.m4a
[5/3/16 11:07:49 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Event Horizon' for user monstermuffin20 at Last.fm: Tue May 03 11:07:48 BST 2016
[5/3/16 11:07:50 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Event Horizon' for user monstermuffin20 at Last.fm: Tue May 03 11:07:48 BST 2016
[5/3/16 11:07:50 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Event Horizon' for user monstermuffin20 at Last.fm: Tue May 03 11:07:48 BST 2016


I looked again today and it's pretty ridiculous how many times the srobbler is scrobbling the same song..

Code: Select all

[5/4/16 11:05:38 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:05:37 BST 2016
[5/4/16 11:05:39 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:05:37 BST 2016
[5/4/16 11:05:40 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:05:37 BST 2016
[5/4/16 11:05:44 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:05:43 BST 2016
[5/4/16 11:08:02 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:08:01 BST 2016
[5/4/16 11:08:03 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'This Is What You Came For (feat. Rihanna)' for user monstermuffin20 at Last.fm: Wed May 04 11:08:01 BST 2016
[5/4/16 11:09:46 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:09:46 BST 2016
[5/4/16 11:11:03 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:11:02 BST 2016
[5/4/16 11:11:04 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:11:02 BST 2016
[5/4/16 11:11:05 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:11:02 BST 2016
[5/4/16 11:11:26 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:11:25 BST 2016
[5/4/16 11:12:26 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:12:25 BST 2016
[5/4/16 11:12:33 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'Apollo' for user monstermuffin20 at Last.fm: Wed May 04 11:12:32 BST 2016
[5/4/16 11:14:35 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:14:34 BST 2016
[5/4/16 11:15:52 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:15:51 BST 2016
[5/4/16 11:15:53 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:15:51 BST 2016
[5/4/16 11:15:54 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:15:51 BST 2016
[5/4/16 11:16:07 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:16:06 BST 2016
[5/4/16 11:16:52 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:16:51 BST 2016
[5/4/16 11:16:53 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:16:52 BST 2016
[5/4/16 11:17:46 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:17:45 BST 2016
[5/4/16 11:17:47 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:17:45 BST 2016
[5/4/16 11:17:48 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:17:45 BST 2016
[5/4/16 11:17:48 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:17:46 BST 2016
[5/4/16 11:18:44 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:18:43 BST 2016
[5/4/16 11:18:45 AM BST]   INFO   AudioScrobblerService   Successfully registered submission for song 'The World Is Yours' for user monstermuffin20 at Last.fm: Wed May 04 11:18:44 BST 2016
[5/4/16 11:18:51 AM BST]   INFO   AudioScrobblerService   Successfully registered now playing for song 'Badboy Style' for user monstermuffin20 at Last.fm: Wed May 04 11:18:50 BST 2016


[mfrt]

I have also observed this. Usually, each song is scrobbled three or four times, but once a track was scrobbled eight times!!

[rb_subsonic]

Same issue here. It happens on Firefox, Chrome, and Safari - don't think it has anything to do with the browser.

The issue seems to be tied to the track changing state... ie start/stop/next/pause or the window regaining focus. If you start a playlist and let it run uninterrupted everything is posted to last.fm normally. If you modify the state of the player using the control buttons or by clicking on items in other playlists things go sideways.

[rb_subsonic]

Just installed the DSUB app on Android. It does not have the issue with multiple posts to LastFM. I think it's just simply how the new web player works that it inadvertently causes the issue.

If you look in the logs, the DSUB client is grabbing the full file from the server, caching it, and all start/pause/stop is handled on the local file.

POST /rest/stream.view?u=ross&p=enc:737562343230393131&v=1.2.0&c=DSub&id=2677&maxBitRate=320

If you watch the network activity of the web player using Chrome developer tools you can see it will continually try to re-download segments of the file using an offset command. I assume this is an optimization strategy to reduce bandwidth. I don't have a pre6.0 version of Subsonic to see if this has always been the behavior but I suspect it's not or we would have seen it before



So basically what I'm seeing in the logs is that each one of these GET requests fires off both the LastFM now playing and submission routines.

[MonsterMuffin]

Is this going to be looked into? It's really annoying and ruining my last.fm stats.

[topdownjimmy]

I've created a ticket in SourceForge, hopefully it'll get noticed there:

https://sourceforge.net/p/subsonic/bugs/193/

Please go add your own details to give it some traction.

[beatsystem]

Just upgraded to 6.0, this is now also happening for me - tune is continually resubmitted every second whilst it is playing:

[11/12/16 19:56:47 CET] INFO AudioScrobblerService Successfully registered now playing for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:37 CET 2016
[11/12/16 19:56:48 CET] INFO AudioScrobblerService Successfully registered submission for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:37 CET 2016
[11/12/16 19:56:49 CET] INFO AudioScrobblerService Successfully registered now playing for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:38 CET 2016
[11/12/16 19:56:50 CET] INFO AudioScrobblerService Successfully registered submission for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:38 CET 2016
[11/12/16 19:56:51 CET] INFO AudioScrobblerService Successfully registered now playing for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:38 CET 2016
[11/12/16 19:56:52 CET] INFO AudioScrobblerService Successfully registered submission for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:38 CET 2016
[11/12/16 19:56:53 CET] INFO AudioScrobblerService Successfully registered now playing for song 'Walking Through the Upside Down' for user at Last.fm: Sun Dec 11 19:56:39 CET 2016

Over and over again, even when the tune is paused - I had to log out to stop it.

https://sourceforge.net/p/subsonic/bugs/193/ indicates it will be fixed in 6.1 - Anyone know when that is due?

Will have to roll back until then I guess...

(EDIT: On a positive note, thanks to Sindre for making it super easy to roll back thus far, it's normally something I dread!)

[topdownjimmy]

According to a message on SourceForge, this will be fixed in 6.1:

https://sourceforge.net/p/subsonic/bugs/193/#8b14