Bug 499745 – last.fm plugin only sometimes submits data

After an evaluation, GNOME has moved from Bugzilla to GitLab. Learn more about GitLab.
No new issues can be reported in GNOME Bugzilla anymore.
To report an issue in a GNOME project, go to GNOME GitLab.
Do not go to GNOME Gitlab for: Bluefish, Doxygen, GnuCash, GStreamer, java-gnome, LDTP, NetworkManager, Tomboy.

Bug 499745 - last.fm plugin only sometimes submits data


Summary:	last.fm plugin only sometimes submits data


Status:	RESOLVED FIXED

Product:	rhythmbox
Classification:	Other
Component:	Plugins (other)
Version:	0.10.x
Hardware:	Other All

Importance:	Normal major
Target Milestone:	---
Assigned To:	RhythmBox Maintainers
QA Contact:	RhythmBox Maintainers

URL:
Whiteboard:

Duplicates:	373513 502672 509220 (view as bug list)
Depends on:
Blocks:

Reported:	2007-11-26 14:36 UTC by Jonathan W
Modified:	2008-08-13 00:37 UTC

See Also:
GNOME target:	---
GNOME version:	---

Attachments
more debug info (2.88 KB, patch) 2007-12-01 23:35 UTC, Jonathan Matthew	none	Details \| Review
should fix it (3.01 KB, patch) 2007-12-02 01:04 UTC, Jonathan Matthew	committed	Details \| Review
fix (or workaround) for stable branch (971 bytes, patch) 2007-12-07 16:39 UTC, Claudio Saavedra	none	Details \| Review
actual fix (for trunk) (5.25 KB, patch) 2007-12-09 04:05 UTC, Jonathan Matthew	committed	Details \| Review

Description Jonathan W 2007-11-26 14:36:58 UTC

Please describe the problem:
Until recently, the plugin has been working fine (as far as I could tell); now it's only submitting songs played seemingly randomly, maybe only as frequently as one song out of 20.

I'm not sure what triggered this, as I've recently upgraded my kernel, various parts of gstreamer, and rhythmbox itself (though I specifically upgraded rhythmbox because of the problem, but it didn't solve anything).

And it doesn't appear to be a problem with last.fm—the audioscrobbler.queue file's timestamp doesn't change often, and seems to agree with the time of the last submission to last.fm.

Steps to reproduce:
1. Some sort of system maintenance(??)
2. Play media as normal in rhythmbox
3. Check last.fm account or audioscrobbler.queue's timestamp


Actual results:
Very infrequent update of tracks played.

Expected results:
Update of tracks played info every time a track is played.

Does this happen every time?


Other information:

Comment 1 Jonathan W 2007-11-30 11:08:53 UTC

It seems unrelated to my kernel upgrade, and behaves the same way under any kernel.  At this point, it's missed submission of hundreds of played tracks, and appears not to have submitted any tracks since the bug was originally submitted, though it updated the timestamp on audioscrobbler.queue once last night.

Comment 2 Jonathan Matthew 2007-11-30 11:19:54 UTC

Output from running 'rhythmbox -D audioscrobbler' in a terminal would help diagnose the problem.

Comment 3 Jonathan W 2007-11-30 11:31:03 UTC

I was wondering if there was such a way to see what it was doing.  The jackd errors correspond to tracks beginning.

jonathan@nimloth:~/.gnome2/rhythmbox$ rhythmbox -D audioscrobbler
(17:25:34) [0x6bc040] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(17:25:34) [0x6bc040] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(17:25:34) [0x6bc040] [rb_audioscrobbler_init] rb-audioscrobbler.c:246: Initialising Audioscrobbler
(17:25:34) [0x6bc040] [rb_audioscrobbler_init] rb-audioscrobbler.c:248: Plugin ID: rbx, Version 0.10.1 (Protocol 1.1)
(17:25:34) [0x6bc040] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1255: Loading Audioscrobbler queue from "file:///home/jonathan/.gnome2/rhythmbox/audioscrobbler.queue"
(17:25:34) [0x6bc040] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:425: Adding Audioscrobbler timer (15 seconds)

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAll' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchArtists' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchAlbums' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'AutoPlaylistSearchTitles' to action group 'AutoPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAll' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchArtists' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchAlbums' to action group 'StaticPlaylistActions'

(rhythmbox:462): Gtk-WARNING **: Refusing to add non-unique action 'StaticPlaylistSearchTitles' to action group 'StaticPlaylistActions'
(17:25:49) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:716: Performing handshake with Audioscrobbler server: http://post.audioscrobbler.com/?hs=true&p=1.1&c=rbx&v=0.10.1&u=firespeaker
(17:25:50) [0x6bc040] [rb_audioscrobbler_do_handshake_cb] rb-audioscrobbler.c:735: Handshake response
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:536: Parsing response, status=200
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: UPTODATE
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:555: UPTODATE
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:560: MD5 challenge: "18446c8d5a728f8e69f9b1f926a635b4"
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:565: Submit URL: "http://87.117.229.205:80/protocol_1.1"
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: INTERVAL 1
(17:25:50) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:610: INTERVAL: 1
(17:26:04) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:26:19) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
sh: jackd: command not found
(17:26:34) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:26:49) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:27:04) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:27:19) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:27:34) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:27:49) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:28:04) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:28:19) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:28:34) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:28:49) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:29:04) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
sh: jackd: command not found
(17:29:19) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(17:29:34) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one

Comment 4 Jonathan Matthew 2007-11-30 12:13:13 UTC

Looks like it's deciding that whatever you're listening to doesn't need to be scrobbled.  The criteria are: longer than 30 seconds, not a radio stream, not a podcast episode, artist and title not "Unknown".

Comment 5 Jonathan W 2007-11-30 12:38:19 UTC

As I said, it's not scrobbling tracks that were previously always scrobbled.  The vast majority of my tracks do not meet any of those criteria anyway.

I'm wondering if it's not a problem in some version of gstreamer, then, where gstreamer might be reporting files to be of improper length or something.

Btw, where in the debugging output do you see that?  I let it play one track through and start another one; each time a track began, it output the jackd error, and I see nothing else in the vicinity of those that suggests that it doesn't want to scrobble the track (though nothing is suggesting that it does want to scrobble it either...).

Comment 6 Jonathan W 2007-11-30 13:15:52 UTC

It just submitted one track, but if I repeat the track it doesn't submit again.  Here's the debugging output from the beginning of the track's playing, until I manually began the next track before that track had finished:

sh: jackd: command not found
(18:53:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:53:48) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:54:03) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:54:18) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:54:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:54:48) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:55:03) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:55:18) [0x6bc040] [rb_audioscrobbler_timeout_cb] rb-audioscrobbler.c:456: Adding song to queue
(18:55:18) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(18:55:18) [0x6bc040] [rb_audioscrobbler_submit_queue] rb-audioscrobbler.c:842: Submitting queue to Audioscrobbler
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1355: Audioscrobbler submission (1 entries):
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1360: 1    artist: %d0%97%d0%b2%d0%b5%d1%80%d0%b8
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1361:       album: 
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1362:       title: %d0%92%d1%81%d0%b5%20%d1%87%d1%82%d0%be%20%d0%ba%d0%b0%d1%81%d0%b0%d0%b5%d1%82%d1%81%d1%8f
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1363:      length: 209
(18:55:18) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1364:   timestamp: 2007%2D11%2D30%2012%3A55%3A18
(18:55:18) [0x6bc040] [rb_audioscrobbler_perform] rb-audioscrobbler.c:648: Submitting to Audioscrobbler: u=firespeaker&s=743ae2c172c38f359db10b5fed7b63c6&a[0]=%d0%97%d0%b2%d0%b5%d1%80%d0%b8&t[0]=%d0%92%d1%81%d0%b5%20%d1%87%d1%82%d0%be%20%d0%ba%d0%b0%d1%81%d0%b0%d0%b5%d1%82%d1%81%d1%8f&b[0]=&m[0]=&l[0]=209&i[0]=2007%2D11%2D30%2012%3A55%3A18&
(18:55:18) [0x6bc040] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:858: Submission response
(18:55:18) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:536: Parsing response, status=200
(18:55:18) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: OK
(18:55:18) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:607: OK
(18:55:18) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: INTERVAL 1
(18:55:18) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:610: INTERVAL: 1
(18:55:18) [0x6bc040] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:862: Queue submitted successfully
(18:55:18) [0x6bc040] [rb_audioscrobbler_save_queue] rb-audioscrobbler.c:1305: Saving Audioscrobbler queue to "/home/jonathan/.gnome2/rhythmbox/audioscrobbler.queue"
(18:55:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
sh: jackd: command not found

Comment 7 Jonathan W 2007-11-30 13:36:29 UTC

Here's another track it decided to submit, but playing it two more times did nothing.  It seems to be occasionally deciding to submit tracks at random.

sh: jackd: command not found
(19:20:03) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:20:18) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:20:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:20:48) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:21:03) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:21:18) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:21:33) [0x6bc040] [rb_audioscrobbler_timeout_cb] rb-audioscrobbler.c:456: Adding song to queue
(19:21:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:21:33) [0x6bc040] [rb_audioscrobbler_submit_queue] rb-audioscrobbler.c:842: Submitting queue to Audioscrobbler
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1355: Audioscrobbler submission (1 entries):
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1360: 1    artist: Le%20R%c3%aave%20du%20Diable
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1361:       album: 
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1362:       title: Le%20Coucou
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1363:      length: 164
(19:21:33) [0x6bc040] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1364:   timestamp: 2007%2D11%2D30%2013%3A21%3A33
(19:21:33) [0x6bc040] [rb_audioscrobbler_perform] rb-audioscrobbler.c:648: Submitting to Audioscrobbler: u=firespeaker&s=743ae2c172c38f359db10b5fed7b63c6&a[0]=Le%20R%c3%aave%20du%20Diable&t[0]=Le%20Coucou&b[0]=&m[0]=&l[0]=164&i[0]=2007%2D11%2D30%2013%3A21%3A33&
(19:21:33) [0x6bc040] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:858: Submission response
(19:21:33) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:536: Parsing response, status=200
(19:21:33) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: OK
(19:21:33) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:607: OK
(19:21:33) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:553: RESPONSE: INTERVAL 1
(19:21:33) [0x6bc040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:610: INTERVAL: 1
(19:21:33) [0x6bc040] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:862: Queue submitted successfully
(19:21:33) [0x6bc040] [rb_audioscrobbler_save_queue] rb-audioscrobbler.c:1305: Saving Audioscrobbler queue to "/home/jonathan/.gnome2/rhythmbox/audioscrobbler.queue"
(19:21:48) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:22:03) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:22:18) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
(19:22:33) [0x6bc040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:687: Not doing handshake; we already have one
sh: jackd: command not found

Comment 8 Jonathan Matthew 2007-12-01 09:20:04 UTC

It'd be great if you could do one of these things:

- install whatever debug info packages are available and trace into the last.fm code to see why it's ignoring the tracks you're listening to

- build the latest rhythmbox release from source (or find a binary package somewhere) and see if the problem still occurs - probably the easier option

Otherwise, we don't really have enough information to figure out what's going on.

Comment 9 Jonathan W 2007-12-01 20:17:22 UTC

Okay, happy to try this.  But I need to know where to get the audioscrobbler plugin source, so I can actually build in support for it.  It seems amazingly difficult to find for open source software, or maybe I'm just using ineffective strategies of looking for it.

Comment 10 Jonathan Matthew 2007-12-01 22:41:15 UTC

Like just about all of our plugins, it's part of the rhythmbox source tree.

Comment 11 Jonathan W 2007-12-01 23:01:24 UTC

Ah, I guess I was missing libsoup, sorry.  Compiled successfully and testing now.

Comment 12 Jonathan W 2007-12-01 23:14:58 UTC

Rhythmbox 0.11.3 is giving me exactly the same trouble.

It almost has to be due to something specific with my setup.  Maybe a library somewhere that's not working quite right?  I'm running Debian unstable, and the problem began with some recent updates, so more people might be noticing this problem soon (assuming it's not something else stupid that I did to my system, though I can't imagine what that would be).

I don't have any experience tracing code through debug info packages, so if someone will provide me with some guidance, I'd be happy to give that option a try.

Comment 13 Jonathan Matthew 2007-12-01 23:35:19 UTC

Created attachment 100012 [details] [review]
more debug info

This patch should give us enough information to figure out why it's ignoring the tracks you're listening to.  Please apply this (patch -p1 < file.diff), rebuild, and run 'rhythmbox -D audioscrobbler' again.

Comment 14 Jonathan W 2007-12-01 23:58:47 UTC

Okay, here's the output after playing a handful of shortish tracks:
jonathan@nimloth:/usr/src/rhythmbox-0.11.3$ /usr/local/bin/rhythmbox -D audioscrobbler
(05:52:32) [0x673040] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(05:52:32) [0x673040] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(05:52:32) [0x673040] [rb_audioscrobbler_init] rb-audioscrobbler.c:303: Initialising Audioscrobbler
(05:52:32) [0x673040] [rb_audioscrobbler_init] rb-audioscrobbler.c:305: Plugin ID: rbx, Version 0.11.3 (Protocol 1.1)
(05:52:32) [0x673040] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1497: Loading Audioscrobbler queue from "file:///home/jonathan/.gnome2/rhythmbox/audioscrobbler.queue"
(05:52:32) [0x673040] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:484: Adding Audioscrobbler timer (15 seconds)
(05:52:47) [0x673040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:883: Performing handshake with Audioscrobbler server: http://post.audioscrobbler.com/?hs=true&p=1.1&c=rbx&v=0.11.3&u=firespeaker
(05:52:47) [0x673040] [rb_audioscrobbler_do_handshake_cb] rb-audioscrobbler.c:902: Handshake response
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:693: Parsing response, status=200
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:710: RESPONSE: UPTODATE
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:712: UPTODATE
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:717: MD5 challenge: "7725204bf42767697138fe34ed5153c2"
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:722: Submit URL: "http://87.117.229.205:80/protocol_1.1"
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:710: RESPONSE: INTERVAL 1
(05:52:47) [0x673040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:767: INTERVAL: 1
(05:52:49) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1312: new entry: file:///data/Music/ogg/Scandinavian/Swedish/Garmarna/Garmarna%20-%20Vittrad%20-%2004%20-%20Kulleritova.ogg
(05:52:49) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird
(05:52:49) [0x673040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:543: entry file:///data/Music/ogg/Scandinavian/Swedish/Garmarna/Garmarna%20-%20Vittrad%20-%2004%20-%20Kulleritova.ogg is queueable
sh: jackd: command not found
(05:53:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:53:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:53:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
sh: jackd: command not found
(05:53:45) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1312: new entry: file:///data/Music/ogg/Celtic/Folk/Dervish/Dervish%20-%20HarmonyHill%20-%2004%20-%20Bellaghy%20Fair.ogg
(05:53:45) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird
(05:53:45) [0x673040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:543: entry file:///data/Music/ogg/Celtic/Folk/Dervish/Dervish%20-%20HarmonyHill%20-%2004%20-%20Bellaghy%20Fair.ogg is queueable
(05:53:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:54:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:54:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:54:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
sh: jackd: command not found
(05:54:42) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1312: new entry: file:///data/Music/ogg/Turkic/Uzbek/Setora/Setora%20-%20Sen%20Borsan%20-%2012%20-%20Yolgizlik.ogg
(05:54:42) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird
(05:54:42) [0x673040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:543: entry file:///data/Music/ogg/Turkic/Uzbek/Setora/Setora%20-%20Sen%20Borsan%20-%2012%20-%20Yolgizlik.ogg is queueable
(05:54:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:55:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:55:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:55:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:55:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
sh: jackd: command not found
(05:55:52) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1312: new entry: file:///data/Music/ogg/Yiddish/Klezmer/Klezmatics/Klezmatics%20-%20Eyn%20Mol.ogg
(05:55:52) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird
(05:55:52) [0x673040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:543: entry file:///data/Music/ogg/Yiddish/Klezmer/Klezmatics/Klezmatics%20-%20Eyn%20Mol.ogg is queueable
(05:56:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:56:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:56:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:56:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:57:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
sh: jackd: command not found
(05:57:15) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1312: new entry: file:///data/Music/ogg/Turkic/Tuvan/Yat-Kha/Yat-Kha%20-%20tuva.rock%20-%2007%20-%20Eki%20Attar.ogg
(05:57:15) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird
(05:57:15) [0x673040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:543: entry file:///data/Music/ogg/Turkic/Tuvan/Yat-Kha/Yat-Kha%20-%20tuva.rock%20-%2007%20-%20Eki%20Attar.ogg is queueable
(05:57:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:57:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:57:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:58:02) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:58:17) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:58:32) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one
(05:58:47) [0x673040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Not doing handshake; we already have one

Comment 15 Jonathan Matthew 2007-12-02 00:31:44 UTC

(05:57:15) [0x673040] [rb_audioscrobbler_song_changed_cb]
rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird

So, the position query is failing at that point.  Hmm.  I'm not sure why we're checking the playing time at that point.  It doesn't seem very useful.

Comment 16 Jonathan W 2007-12-02 00:58:20 UTC

So.... Is that what's causing the problems (does that mean the bug's not unconfirmed anymore)?  If so, is there a temporary fix I could employ?

Comment 17 Jonathan Matthew 2007-12-02 01:04:12 UTC

Created attachment 100022 [details] [review]
should fix it

Revert the previous patch (patch -p1 -R < file.diff) and apply this one.  This just comments out the check that was failing previously.  I'm not sure if there was a good reason for this check, though, so I'm not going to commit this just yet.

Comment 18 Jonathan W 2007-12-02 01:24:38 UTC

Cool, that works, thanks.  I'm still see that message on occasion, though:
(07:16:29) [0x673040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1318: got playing-song-changed at -1 seconds.. weird

Comment 19 Jonathan Matthew 2007-12-02 01:28:05 UTC

It still prints that message, but doesn't do anything else with that information.

Thanks for your help in tracking this down.

Comment 20 Claudio Saavedra 2007-12-07 16:33:37 UTC

Some observations I have after having tracked this a few:

- To print an unsigned int, you should use %o. With that, you see that time has value 37777777777. I see there are conversions from long (returned by  rb_player_get_time) to guint (which is what rb_shell_player_get_playing_time uses) and then to int. Maybe I'm on crack, but couldn't it be related to that? I'm really confused about what the *real* value of current playing time is.

- Regarding the check for time < 15, from this commit [1], seems to me that the idea of that check is to ensure that if we repeat a song immediately after having played it, we are playing it from the beginning before scrobbling it again. I'm not sure if that check is sound, and it seems to me that it's not. I'd vouch for disabling it.

[1] http://svn.gnome.org/viewvc/rhythmbox/branches/RHYTHMBOX-0_10/plugins/audioscrobbler/rb-audioscrobbler.c?view=diff&r1=4249&r2=4250

Comment 21 Claudio Saavedra 2007-12-07 16:39:21 UTC

Created attachment 100540 [details] [review]
fix (or workaround) for stable branch

Comment 22 Jonathan Matthew 2007-12-09 03:39:47 UTC

rb_shell_player_get_playing_time returns -1 when the position query on the playback pipeline fails.  This seems to happen immediately after we attempt to start playback now, although it didn't when I added that check.

That check is there to avoid weirdness in the future if we change the meaning of the playing-song-changed signal.

We can't (or at least shouldn't) make rb_shell_player_get_playing_time return a signed value, as that would break the dbus interface.  Instead, I think it should return an error when the position query fails.

I've committed the additional debug output from my patch.  The bit that actually tries to fix the problem is wrong.

Comment 23 Jonathan Matthew 2007-12-09 04:05:07 UTC

Created attachment 100623 [details] [review]
actual fix (for trunk)

This makes rb_shell_player_get_playing_time return an error if it can't get the playback position.

Comment 24 Bastien Nocera 2007-12-09 14:42:23 UTC

*** Bug 502672 has been marked as a duplicate of this bug. ***

Comment 25 Lee Aylward 2007-12-16 03:34:00 UTC

I am running the latest rhythmbox svn (on debian unstable) and am also unable to submit any tracks to last.fm as of yet. The plugin configuration page shows a Status of "OK", 0 Queued tracks, 0 Submitted tracks, and a Last submission time of "Never". I do not get any useful errors from -D audioscrobbler. Just this:

(21:08:55) [0x6a4040] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(21:08:55) [0x6a4040] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(21:08:55) [0x6a4040] [rb_audioscrobbler_init] rb-audioscrobbler.c:303: Initialising Audioscrobbler
(21:08:55) [0x6a4040] [rb_audioscrobbler_init] rb-audioscrobbler.c:305: Plugin ID: rbx, Version 0.11.3 (Protocol 1.1)
(21:08:55) [0x6a4040] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1487: Loading Audioscrobbler queue from "file:///home/leedo/.gnome2/rhythmbox/audioscrobbler.queue"
(21:08:55) [0x6a4040] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:484: Adding Audioscrobbler timer (15 seconds)
(21:09:10) [0x6a4040] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:876: Performing handshake with Audioscrobbler server: http://post.audioscrobbler.com/?hs=true&p=1.1&c=rbx&v=0.11.3&u=******
(21:09:12) [0x6a4040] [rb_audioscrobbler_do_handshake_cb] rb-audioscrobbler.c:895: Handshake response
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:687: Parsing response, status=200
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:704: RESPONSE: UPTODATE
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:706: UPTODATE
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:711: MD5 challenge: "974f1ba80a375db985b8488e3c05d381"
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:716: Submit URL: "http://87.117.229.205:80/protocol_1.1"
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:704: RESPONSE: INTERVAL 1
(21:09:12) [0x6a4040] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:761: INTERVAL: 1
(21:10:29) [0x6a4040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1305: new entry: daap://192.168.1.100:3689/databases/1/items/26038.mp3?session-id=11
(21:10:29) [0x6a4040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:538: entry daap://192.168.1.100:3689/databases/1/items/26038.mp3?session-id=11 is queueable
(21:14:25) [0x6a4040] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:841: Too soon; time=1197774865, handshake_next=1197776350

above line * 20

(21:14:38) [0x6a4040] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1305: new entry: daap://192.168.1.100:3689/databases/1/items/26037.mp3?session-id=11
(21:14:38) [0x6a4040] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:538: entry daap://192.168.1.100:3689/databases/1/items/26037.mp3?session-id=11 is queueable

Comment 26 Jonathan Matthew 2007-12-16 06:55:07 UTC

That looks like a different problem - it appears it's forgetting it has done a handshake.

I've committed the patch, but apparently there's more to be done.

Comment 27 Lee Aylward 2007-12-16 21:53:59 UTC

Some more info that I think explains my problem:
I had opened the last.fm plugin config to check the submission stats, and when I hit Close it re-saved my login credentials (even though they hadn't changed). That is why it was trying to renegotiate the handshake. Now if I launch the latest svn version and listen to a song it successfully submits to Last.fm.

I think it may be a good idea to check if the login credentials have actually changed before saving them to gconf and re-negotiating the handshake.

Comment 28 Julian 2007-12-17 16:31:32 UTC

I tried starting rhythmbox and reentering my login credentials, but that changed nothing. here's the debug-trace:
$ rhythmbox -D audioscrobbler
(17:02:16) [0x6ab520] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(17:02:16) [0x6ab520] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(17:02:16) [0x6ab520] [rb_audioscrobbler_init] rb-audioscrobbler.c:303: Initialising Audioscrobbler
(17:02:16) [0x6ab520] [rb_audioscrobbler_init] rb-audioscrobbler.c:305: Plugin ID: rbx, Version 0.11.3 (Protocol 1.1)
(17:02:16) [0x6ab520] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1468: Loading Audioscrobbler queue from "file:///home/iLuvatar/.gnome2/rhythmbox/audioscrobbler.queue"
(17:02:16) [0x6ab520] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:482: Adding Audioscrobbler timer (15 seconds)
(17:02:25) [0x6ab520] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1107: Syncing data with preferences window
(17:02:25) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:25) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:28) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:28) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:28) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:28) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:29) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(17:02:30) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:30) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:859: Performing handshake with Audioscrobbler server: http://post.audioscrobbler.com/?hs=true&p=1.1&c=rbx&v=0.11.3&u=zYroX
(17:02:31) [0x6ab520] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1107: Syncing data with preferences window
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:31) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:32) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:32) [0x6ab520] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1232: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(17:02:34) [0x6ab520] [rb_audioscrobbler_do_handshake_cb] rb-audioscrobbler.c:878: Handshake response
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:669: Parsing response, status=200
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:686: RESPONSE: UPTODATE
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:688: UPTODATE
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:693: MD5 challenge: "8f06e23b31c5ffd81fed92291d289abd"
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:698: Submit URL: "http://87.117.229.205:80/protocol_1.1"
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:686: RESPONSE: INTERVAL 1
(17:02:34) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:743: INTERVAL: 1
(17:02:34) [0x6ab520] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1107: Syncing data with preferences window

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse0:src returned caps which are not a real subset of its template caps

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse0:src returned caps which are not a real subset of its template caps
(17:02:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:03:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:03:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:03:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:03:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:04:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:04:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:04:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:04:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:05:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:05:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:05:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:05:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:06:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:06:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:06:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:06:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:07:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:07:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:07:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse1:src returned caps which are not a real subset of its template caps

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse1:src returned caps which are not a real subset of its template caps
(17:07:46) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:07:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:08:01) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:08:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:08:16) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:08:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:08:31) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:08:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:08:46) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:08:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:09:01) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:09:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:09:16) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:09:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:09:31) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:09:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:09:46) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:09:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:10:01) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:10:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:10:16) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:10:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:10:31) [0x6ab520] [maybe_add_current_song_to_queue] rb-audioscrobbler.c:576: Adding currently playing song to queue
(17:10:31) [0x6ab520] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1107: Syncing data with preferences window
(17:10:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:10:31) [0x6ab520] [rb_audioscrobbler_submit_queue] rb-audioscrobbler.c:1009: Submitting queue to Audioscrobbler
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1584: Audioscrobbler submission (1 entries): 
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1596: 1    artist: Boards Of Canada
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1597:       album: Trans Canada Highway
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1598:       title: Left Side Drive
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1599:      length: 320
(17:10:31) [0x6ab520] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1602:   timestamp: 2007%2D12%2D17%2016%3A10%3A31
(17:10:31) [0x6ab520] [rb_audioscrobbler_perform] rb-audioscrobbler.c:781: Submitting to Audioscrobbler: u=zYroX&s=42679f69dd56c802722359fcbd980a95&a[0]=Boards%20Of%20Canada&t[0]=Left%20Side%20Drive&b[0]=Trans%20Canada%20Highway&m[0]=&l[0]=320&i[0]=2007%2D12%2D17%2016%3A10%3A31&
(17:10:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:10:52) [0x6ab520] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:1036: Submission response
(17:10:52) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:669: Parsing response, status=200
(17:10:52) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:686: RESPONSE: OK
(17:10:52) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:740: OK
(17:10:52) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:686: RESPONSE: INTERVAL 1
(17:10:52) [0x6ab520] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:743: INTERVAL: 1
(17:10:52) [0x6ab520] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:1040: Queue submitted successfully
(17:10:52) [0x6ab520] [rb_audioscrobbler_save_queue] rb-audioscrobbler.c:1537: Saving Audioscrobbler queue to "/home/iLuvatar/.gnome2/rhythmbox/audioscrobbler.queue"
(17:10:52) [0x6ab520] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1107: Syncing data with preferences window
(17:11:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:11:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:11:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:11:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:12:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:12:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:12:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:12:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:13:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse2:src returned caps which are not a real subset of its template caps

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse2:src returned caps which are not a real subset of its template caps
(17:13:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:13:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:13:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:14:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:14:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse3:src returned caps which are not a real subset of its template caps

(rhythmbox:3924): GStreamer-WARNING **: pad mpegaudioparse3:src returned caps which are not a real subset of its template caps
(17:14:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:14:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:15:01) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:15:16) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:15:31) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:15:46) [0x6ab520] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:817: Not doing handshake; we already have one
(17:15:56) [0x6ab520] [rb_audioscrobbler_finalize] rb-audioscrobbler.c:393: Finalizing Audioscrobbler
(17:15:56) [0x6ab520] [rb_audioscrobbler_plugin_finalize] rb-audioscrobbler-plugin.c:100: RBAudioscrobblerPlugin finalising
$ 

scrobbled just one song

Comment 29 Jonathan Matthew 2008-01-13 21:31:59 UTC

*** Bug 509220 has been marked as a duplicate of this bug. ***

Comment 30 Jonathan W 2008-02-08 13:02:17 UTC

Recently I was listening to a playlist on random, and a track came up that I didn't want to hear, so I hit next within about 20 seconds of it starting.  I listened to the next track for a few seconds before deciding I actually did want to hear previous track, and hit back.  Later, I noticed that it scrobbled the track twice.  I haven't been able to reproduce this, but I wonder if it's not related to that check that was taken out of the code.

I'm still using the patched version of rhythmbox I compiled earlier.

Comment 31 Jason T 2008-02-20 18:46:31 UTC

I'm quite new to Linux and I was wondering  how to apply the patches that have been uploaded. I tried running the commands suggested, but I couldn't figure out how to get them to work.

Also, should I download the files as cgi's or should they be something else?

And are the two committed patches for different problems or is one better than the other?

Thanks in advance.

Comment 32 ulrik sverdrup 2008-04-12 02:53:45 UTC

I had this issue exactly as reported here with rhythmbox 0.10.x, but it appears fixed to me with the version I have now which is (debian/lenny) 0.11.5-1.

Comment 33 Sven Arvidsson 2008-04-17 12:37:12 UTC

Another Debian user have reported that this seems fixed (or at least much better) in 0.11.5.
http://bugs.debian.org/461413

Comment 34 Claudio Saavedra 2008-04-17 12:55:38 UTC

Agreed, this seems fixed in the 0.11.x series.

Comment 35 Thomas Zajic 2008-04-17 14:48:58 UTC

Yup, same here. No more missing submissions with 0.11.5.

Comment 36 Jonathan W 2008-04-17 17:31:37 UTC

Seems so to me too, though did recently (read: not long after upgrading) get an error on last.fm saying that "Some tracks you submitted have not been added to your profile for the following reason: Spam protection triggered: You submitted a track dated earlier than your last submission."  I haven't played with the time zone info on my computer, so I'm a little suspicious.  Possibly unrelated though.

Comment 37 Fabio 2008-04-17 17:34:58 UTC

yes i receivet the same error from last.fm

Comment 38 Thomas Zajic 2008-04-17 18:25:37 UTC

Sounds familiar, and I *think* I know how to trigger it. It usually happens to me when I do the following:

- in the morning, at home, play a track, and pause it within 15 seconds (or whatever the threshold for a last.fm submission is)
- during the day, at work, play and submit tracks as usual
- in the evening, back at home, resume/unpause the song that I paused in the morning (yes, I leave my PC running 24/7)
- ta-daaaaa, "Spam protection"

My guess is that once the track has been played long enough for a last.fm submission, Rhythmbox takes the original timestamp from back when the track started in the morning, even though the submission only happens in the evening. I haven't looked at the source code, but that would definitely explain the "earlier [ie. in the morning] than your last submission [ie. during the day" part.

I don't do this on purpose, of course, it just happens from time to time. :-)

An easy fix would probably be to reset the submission threshold on "pause", and restart from 0:00 on "unpause".

Comment 39 Jonathan W 2008-04-18 07:10:02 UTC

I only have one computer atm though.  It's possible that it arose from going back and playing recently listened-to songs though?  That's all I can think of that I've done that might have this effect [if things aren't behaving properly].

Comment 40 Raffaele Sandrini 2008-05-15 19:08:37 UTC

It is still (0.11.5) not working here. It seems the first song was scrobbled but the rest not. The log talks about problems getting the track length. But submits it either way the first time. I run a unstable gnome system here, so all packages are on the most recent unstable release state.

Log:
(20:59:49) [0x9b1e028] [register_rb_plugin] rb-audioscrobbler-plugin.c:74: Registering plugin RBAudioscrobblerPlugin
(20:59:49) [0x9b1e028] [rb_audioscrobbler_plugin_init] rb-audioscrobbler-plugin.c:92: RBAudioscrobblerPlugin initialising
(20:59:49) [0x9b1e028] [rb_audioscrobbler_init] rb-audioscrobbler.c:312: Initialising Audioscrobbler
(20:59:49) [0x9b1e028] [rb_audioscrobbler_init] rb-audioscrobbler.c:314: Plugin ID: rbx, Version 0.11.5 (Protocol 1.1)
(20:59:49) [0x9b1e028] [rb_audioscrobbler_load_queue] rb-audioscrobbler.c:1533: Loading Audioscrobbler queue from "file:///home/rasa/.gnome2/rhythmbox/audioscrobbler.queue"
(20:59:49) [0x9b1e028] [rb_audioscrobbler_add_timeout] rb-audioscrobbler.c:493: Adding Audioscrobbler timer (15 seconds)
(21:00:04) [0x9b1e028] [rb_audioscrobbler_do_handshake] rb-audioscrobbler.c:905: Performing handshake with Audioscrobbler server: http://post.audioscrobbler.com/?hs=true&p=1.1&c=rbx&v=0.11.5&u=haldejung
(21:00:04) [0x9b1e028] [rb_audioscrobbler_do_handshake_cb] rb-audioscrobbler.c:929: Handshake response
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:701: Parsing response, status=200
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:730: RESPONSE: UPTODATE
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:732: UPTODATE
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:737: MD5 challenge: "0d1cc86af39241faba2c9eab5c56bd2a"
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:742: Submit URL: "http://87.117.229.205:80/protocol_1.1"
(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:730: RESPONSE: INTERVAL 1

(21:00:04) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:787: INTERVAL: 1

(21:00:11) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1345: new entry: file:///home/rasa/music/Alanis%20Morissette/Supposed%20Former%20Infatuation%20Junkie/01%20-%20Alanis%20Morissette%20-%20Front%20Row.ogg
(21:00:11) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1353: didn't get playing time; assuming 0
(21:00:11) [0x9b1e028] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:547: entry file:///home/rasa/music/Alanis%20Morissette/Supposed%20Former%20Infatuation%20Junkie/01%20-%20Alanis%20Morissette%20-%20Front%20Row.ogg is queueable
(21:00:19) [0x9b1e028] [rb_audioscrobbler_submit_queue] rb-audioscrobbler.c:1060: Submitting queue to Audioscrobbler
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1649: Audioscrobbler submission (1 entries): 
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1661: 1    artist: Alanis%20Morissette
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1662:       album: Supposed%20Former%20Infatuation%20Junkie
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1663:       title: Front%20Row
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1664:      length: 254
(21:00:19) [0x9b1e028] [rb_audioscrobbler_print_queue] rb-audioscrobbler.c:1667:   timestamp: 2008%2D05%2D15%2016%3A57%3A25
(21:00:19) [0x9b1e028] [rb_audioscrobbler_perform] rb-audioscrobbler.c:828: Submitting to Audioscrobbler: u=haldejung&s=2a35638c772f0b1d2d1986fcd0105214&a[0]=Alanis%2520Morissette&t[0]=Front%2520Row&b[0]=Supposed%2520Former%2520Infatuation%2520Junkie&m[0]=&l[0]=254&i[0]=2008%2D05%2D15%2016%3A57%3A25&
(21:00:19) [0x9b1e028] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:1092: Submission response
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:701: Parsing response, status=200
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:730: RESPONSE: OK
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:784: OK
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:730: RESPONSE: INTERVAL 1
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:787: INTERVAL: 1
(21:00:19) [0x9b1e028] [rb_audioscrobbler_parse_response] rb-audioscrobbler.c:730: RESPONSE: 
(21:00:19) [0x9b1e028] [rb_audioscrobbler_submit_queue_cb] rb-audioscrobbler.c:1096: Queue submitted successfully
(21:00:19) [0x9b1e028] [rb_audioscrobbler_save_queue] rb-audioscrobbler.c:1602: Saving Audioscrobbler queue to "/home/rasa/.gnome2/rhythmbox/audioscrobbler.queue"
(21:00:44) [0x9b1e028] [rb_audioscrobbler_preferences_sync] rb-audioscrobbler.c:1163: Syncing data with preferences window
(21:00:44) [0x9b1e028] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1288: GConf key updated: "/apps/rhythmbox/audioscrobbler/password"
(21:00:44) [0x9b1e028] [rb_audioscrobbler_gconf_changed_cb] rb-audioscrobbler.c:1288: GConf key updated: "/apps/rhythmbox/audioscrobbler/username"
(21:00:49) [0x9b1e028] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:870: Too soon; time=1210878049, handshake_next=1210879804
(21:00:51) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1345: new entry: file:///home/rasa/music/Alanis%20Morissette/Supposed%20Former%20Infatuation%20Junkie/02%20-%20Alanis%20Morissette%20-%20Baba.ogg
(21:00:51) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1353: didn't get playing time; assuming 0
(21:00:51) [0x9b1e028] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:547: entry file:///home/rasa/music/Alanis%20Morissette/Supposed%20Former%20Infatuation%20Junkie/02%20-%20Alanis%20Morissette%20-%20Baba.ogg is queueable
(21:01:04) [0x9b1e028] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:870: Too soon; time=1210878064, handshake_next=1210879804
(21:01:19) [0x9b1e028] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:870: Too soon; time=1210878079, handshake_next=1210879804
(21:01:28) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1345: new entry: file:///home/rasa/music/Apollo%20440/Electro%20Glide%20In%20Blue/Apollo%20440%20-%2001%20-%20Stealth%20Overture.mp3
(21:01:28) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1353: didn't get playing time; assuming 0
(21:01:28) [0x9b1e028] [rb_audioscrobbler_is_queueable] rb-audioscrobbler.c:547: entry file:///home/rasa/music/Apollo%20440/Electro%20Glide%20In%20Blue/Apollo%20440%20-%2001%20-%20Stealth%20Overture.mp3 is queueable
(21:01:34) [0x9b1e028] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:870: Too soon; time=1210878094, handshake_next=1210879804
(21:01:49) [0x9b1e028] [rb_audioscrobbler_should_handshake] rb-audioscrobbler.c:870: Too soon; time=1210878109, handshake_next=1210879804
(21:02:02) [0x9b1e028] [rb_audioscrobbler_song_changed_cb] rb-audioscrobbler.c:1342: called with no playing entry
(21:02:02) [0x9b1e028] [rb_audioscrobbler_dispose] rb-audioscrobbler.c:352: disposing audioscrobbler
(21:02:02) [0x9b1e028] [rb_audioscrobbler_finalize] rb-audioscrobbler.c:404: Finalizing Audioscrobbler
(21:02:02) [0x9b1e028] [rb_audioscrobbler_plugin_finalize] rb-audioscrobbler-plugin.c:100: RBAudioscrobblerPlugin finalising

Comment 41 Jonathan Matthew 2008-06-09 11:08:49 UTC

What's happening there is that after the initial handshake, it decides that you changed your username or password (maybe you actually did, doesn't really matter), so it forgets it has done a handshake and doesn't reset the next-handshake timer, which means it won't do one for another half hour.

I've committed a fix for this, and I'm going to close this bug because it's too long.  If further problems arise, please open a new bug.

Comment 42 Jonathan Matthew 2008-08-13 00:37:37 UTC

*** Bug 373513 has been marked as a duplicate of this bug. ***