354757 – Amarok stops playing after some tracks

Bug 354757 - Amarok stops playing after some tracks

Summary: Amarok stops playing after some tracks

Status:	RESOLVED WORKSFORME

Alias:	None

Product:	amarok
Classification:	Applications
Component:	general (show other bugs)
Version:	2.8.0
Platform:	Fedora RPMs Linux

Importance:	NOR normal
Target Milestone:	2.9
Assignee:	Amarok Developers

URL:
Keywords:

Depends on:
Blocks:

Reported:	2015-11-02 22:28 UTC by sedrubal
Modified:	2016-02-14 23:04 UTC (History)
CC List:	1 user (show)

See Also:
Latest Commit:
Version Fixed In:
Sentry Crash Report:

Attachments
Add an attachment

Note You need to log in before you can comment on or make changes to this bug.

Description sedrubal 2015-11-02 22:28:03 UTC

Sometimes Amarok plays its playlist for a while and then after a track it suddenly stops playing even if the playlist is long enough to continue playing. Unfortunatley I haven't figured it out, why this could happen.

Reproducible: Sometimes

Steps to Reproduce:
1. Add many tracks to the playlist
2. start playing and configure "Track progression" > "Random Tracks"
3. wait an indefinite time

Actual Results:  
amarok stopped to play

Expected Results:  
amarok should stop at the earliest, when every track of the playlist was played

Comment 1 Myriam Schweingruber 2015-11-03 13:05:21 UTC

Which Phonon backend and version of it do you use, which KDE release?
Are the tracks all files or are there also streams?
Where are the tracks located? Local, remote, different file system?
What type of tracks are there, is there a difference int rack types when the stop happens?
FWIW: The stopping on track change going to a different type of track is a long standing bug with the Phonon-backend-gstreamer, and apparently an upstream bug at gstreamer itself.

Comment 2 sedrubal 2015-11-03 14:47:42 UTC

Phonon GStreamer: 4.8.2
Amarok: 2.8.0
Fedora 22
Plasma-desktop: 5.4.2
KDE: 4.14.11
Only local tracks (ogg), no streams.

Comment 3 Myriam Schweingruber 2015-11-03 16:51:11 UTC

Same here, we need an output of what happens when the playback stops, but before you will have to enable the phonon debugging to get more useful information. Please set the environment variables as described here: https://techbase.kde.org/Development/Tutorials/Debugging/Phonon#Environment_Variables, then run amarok in a konsole with the arguments -d --nofork

Comment 4 sedrubal 2015-11-03 22:21:47 UTC

Sorry, this output is a bit huge, but I don't know what you need... This happened after hitting next multiple times, as I didn't want to wait to happen this itself.

amarok: BEGIN: void EngineController::seekTo(int) 
amarok:   [EngineController] seek to:  199943 
PHONON-GST   BEGIN: virtual void Phonon::Gstreamer::MediaObject::seek(qint64) 
PHONON-GST   END__: virtual void Phonon::Gstreamer::MediaObject::seek(qint64) [Took: 0.32s] 
amarok: END__: void EngineController::seekTo(int) [Took: 0.32s] 
"PulseSupport(2): Found PulseAudio stream index 58 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
"PulseSupport(2): Found PulseAudio stream index 58 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
amarok: BEGIN: void Playlist::Actions::next() 
amarok:   BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:   END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:   END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) [Took: 0s] 
amarok:   BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:     BEGIN: void EngineController::play(Meta::TrackPtr, uint, bool) 
amarok:       BEGIN: void EngineController::stop(bool, bool) 
amarok:         [EngineController] slotTrackFinishedPlaying( "" - "" - "07._Von_Geist_zu_Geist" , 0.894774 ) 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST               State change 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST                 Moving from "playing"  Phonon::PlayingState  to "paused"  Phonon::PausedState  
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
PHONON-GST               State change 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) 
PHONON-GST                 -1 
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) [Took: 0s] 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST                 Moving from "paused"  Phonon::PausedState  to "ready"  Phonon::StoppedState  
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.008s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.009s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0.009s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "" )  
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
amarok:       END__: void EngineController::stop(bool, bool) [Took: 0.029s] 
amarok:       [EngineController] play: bounded is  QObject(0x0)  current "Happy Ending" 
amarok:       [EngineController] Just a normal, boring track... :-P 
amarok:       BEGIN: void EngineController::playUrl(const KUrl&, uint, bool) 
amarok:         [EngineController] URL:  KUrl("file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg") "file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg" 
amarok:         [EngineController] Offset:  0 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg" )  
PHONON-GST           uri "file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg" 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
PHONON-GST         Fading to 1 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::play() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::PlayingState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "playing" 
0:09:14.823990600 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST               BEGIN: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) 
PHONON-GST               END__: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) [Took: 0s] 
0:09:14.824319688 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.002s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.002s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::play() [Took: 0.003s] 
amarok:       END__: void EngineController::playUrl(const KUrl&, uint, bool) [Took: 0.003s] 
amarok:     END__: void EngineController::play(Meta::TrackPtr, uint, bool) [Took: 0.033s] 
amarok:   END__: void Playlist::Actions::play(quint64, bool) [Took: 0.033s] 
amarok: END__: void Playlist::Actions::next() [Took: 0.033s] 
amarok: [EngineController] slotStateChanged from  Phonon::PlayingState  to  Phonon::PausedState  
amarok: MPRIS2: Queueing up a PropertiesChanged signal 
amarok: [EngineController] slotTrackLengthChanged( -1 ) 
amarok: BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok: END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok: [EngineController] slotStateChanged from  Phonon::PausedState  to  Phonon::StoppedState  
"PulseSupport(2): Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453} is gone at the PA end. Marking it as invalid in our cache as we may reuse it." 
"PulseSupport(2): Found PulseAudio stream index 59 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
PHONON-GST Stream changed to file:///home/se/music/_-_tom_swoon_lush_simon-ahead_of_us_e.ogg 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::handleStreamChange() 
PHONON-GST   false 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::handleStreamChange() [Took: 0s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged(): new meta-data: QMap(("xesam:url", QVariant(QUrl, QUrl("file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg") ) ) )  
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:   [EngineController] Using gain of -1 with relative peak of -1 
PHONON-GST   Fading to 0.891251 
amarok:   BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::slotActiveTrackChanged(quint64) 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     END__: void Playlist::NonlinearTrackNavigator::slotActiveTrackChanged(quint64) [Took: 0s] 
amarok:     MPRIS2: Queueing up a PropertiesChanged signal 
amarok:     BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:       BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
PHONON-GST         State change 
PHONON-GST         State change 
amarok:       END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0.16s] 
amarok:     END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0.16s] 
amarok:   END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0.16s] 
amarok:   BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:     found  0  timecodes on this track 
amarok:   END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:   BEGIN: void CurrentEngine::trackPlaying(Meta::TrackPtr) 
amarok:     [CurrentEngine] updating track "Happy Ending" 
amarok:   END__: void CurrentEngine::trackPlaying(Meta::TrackPtr) [Took: 0.004s] 
amarok:   BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:   END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0s] 
amarok: END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0.19s] 
amarok: [CoverFetcher] Queueing automatic cover fetch for: "Fusion Festival 2014" 
amarok: BEGIN: void ScriptManager::notifyFetchLyrics(const QString&, const QString&) 
amarok:   SCRIPT "LyricWiki" :  "request URL: http://lyrics.wikia.com/api.php?action=query&prop=revisions&rvprop=content&format=xml&titles=Mika:Happy_Ending" 
amarok:   BEGIN: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) 
amarok:   END__: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) [Took: 0s] 
amarok: END__: void ScriptManager::notifyFetchLyrics(const QString&, const QString&) [Took: 0s] 
amarok: MPRIS2: Album image location is KUrl("file:///home/se/.kde/share/apps/amarok/albumcovers/large/01e4e84d2e9f1af8171056655349c7ef") 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok: BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:   BEGIN: virtual void Playlist::RandomTrackNavigator::planOne() 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::RandomTrackNavigator::planOne() [Took: 0s] 
amarok: END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST   Moving from "ready"  Phonon::StoppedState  to "paused"  Phonon::PausedState  
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) 
PHONON-GST   273214 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST   Moving from "paused"  Phonon::PausedState  to "playing"  Phonon::PlayingState  
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged() triggered by phonon, but we've already seen exactly the same metadata recently. Ignoring for now. 
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged(): new meta-data: QMap(("xesam:album", QVariant(QString, "Life In Cartoon Motion") ) ( "xesam:author" ,  QVariant(QString, "Mika") ) ( "xesam:genre" ,  QVariant(QString, "Pop") ) ( "xesam:title" ,  QVariant(QString, "Happy Ending") ) ( "xesam:url" ,  QVariant(QUrl, QUrl("file:///home/se/music/Mika/Life_In_Cartoon_Motion/10_-_Happy_Ending.ogg") ) ) )  
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: [EngineController] slotStateChanged from  Phonon::StoppedState  to  Phonon::PausedState  
amarok: MPRIS2: Queueing up a PropertiesChanged signal 
amarok: [EngineController] slotTrackLengthChanged( 273214 ) 
amarok: BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:   found  0  timecodes on this track 
amarok: END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok: MPRIS2: Album image location is KUrl("file:///home/se/.kde/share/apps/amarok/albumcovers/large/01e4e84d2e9f1af8171056655349c7ef") 
amarok: [EngineController] slotStateChanged from  Phonon::PausedState  to  Phonon::PlayingState  
"PulseSupport(2): Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453} is gone at the PA end. Marking it as invalid in our cache as we may reuse it." 
"PulseSupport(2): Found PulseAudio stream index 60 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
"PulseSupport(2): Found PulseAudio stream index 60 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
"PulseSupport(2): Found PulseAudio stream index 60 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
"PulseSupport(2): Found PulseAudio stream index 60 for Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453}" 
amarok: BEGIN: void AmarokScript::AmarokLyricsScript::showLyrics(const QString&) const 
amarok:   BEGIN: void LyricsManager::lyricsResult(const QString&, bool) 
amarok:     [LyricsManager] setting cached lyrics... 
amarok:     BEGIN: void Context::ContextView::slotMetadataChanged(Meta::TrackPtr) 
amarok:     END__: void Context::ContextView::slotMetadataChanged(Meta::TrackPtr) [Took: 0s] 
amarok:     BEGIN: void CurrentEngine::metadataChanged(Meta::TrackPtr) 
amarok:     END__: void CurrentEngine::metadataChanged(Meta::TrackPtr) [Took: 0s] 
amarok:     MPRIS2: Album image location is KUrl("file:///home/se/.kde/share/apps/amarok/albumcovers/large/01e4e84d2e9f1af8171056655349c7ef") 
amarok:     MPRIS2: Queueing up a PropertiesChanged signal 
amarok:     BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:     END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0s] 
amarok:     BEGIN: void LyricsSubject::sendNewLyrics(const LyricsData&) 
amarok:     END__: void LyricsSubject::sendNewLyrics(const LyricsData&) [Took: 0s] 
amarok:   END__: void LyricsManager::lyricsResult(const QString&, bool) [Took: 0.029s] 
amarok: END__: void AmarokScript::AmarokLyricsScript::showLyrics(const QString&) const [Took: 0.029s] 
amarok: BEGIN: void LyricsEngine::onTrackMetadataChanged(Meta::TrackPtr) 
amarok: END__: void LyricsEngine::onTrackMetadataChanged(Meta::TrackPtr) [Took: 0s] 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok: BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok: END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok: BEGIN: void Playlist::Actions::next() 
amarok:   BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:   END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:   END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) [Took: 0s] 
amarok:   BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:     BEGIN: void EngineController::play(Meta::TrackPtr, uint, bool) 
amarok:       BEGIN: void EngineController::stop(bool, bool) 
amarok:         [EngineController] slotTrackFinishedPlaying( "Mika" - "Life In Cartoon Motion" - "Happy Ending" , 0.00908059 ) 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST               State change 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST                 Moving from "playing"  Phonon::PlayingState  to "paused"  Phonon::PausedState  
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
PHONON-GST               State change 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) 
PHONON-GST                 -1 
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleDurationChange(qint64) [Took: 0s] 
PHONON-GST               BEGIN: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) 
PHONON-GST                 Moving from "paused"  Phonon::PausedState  to "ready"  Phonon::StoppedState  
PHONON-GST               END__: void Phonon::Gstreamer::MediaObject::handleStateChange(GstState, GstState) [Took: 0s] 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.004s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.004s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0.004s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "" )  
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
amarok:       END__: void EngineController::stop(bool, bool) [Took: 0.005s] 
amarok:       [EngineController] play: bounded is  QObject(0x0)  current "02._Back2House" 
amarok:       [EngineController] Just a normal, boring track... :-P 
amarok:       BEGIN: void EngineController::playUrl(const KUrl&, uint, bool) 
amarok:         [EngineController] URL:  KUrl("file:///home/se/music/_-_02._Back2House.ogg") "file:///home/se/music/_-_02._Back2House.ogg" 
amarok:         [EngineController] Offset:  0 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "file:///home/se/music/_-_02._Back2House.ogg" )  
PHONON-GST           uri "file:///home/se/music/_-_02._Back2House.ogg" 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
PHONON-GST         Fading to 1 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::play() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::PlayingState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "playing" 
0:09:17.700759021 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST               BEGIN: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) 
PHONON-GST               END__: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) [Took: 0s] 
0:09:17.700946815 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.002s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.002s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::play() [Took: 0.002s] 
amarok:       END__: void EngineController::playUrl(const KUrl&, uint, bool) [Took: 0.002s] 
amarok:     END__: void EngineController::play(Meta::TrackPtr, uint, bool) [Took: 0.008s] 
amarok:   END__: void Playlist::Actions::play(quint64, bool) [Took: 0.008s] 
amarok: END__: void Playlist::Actions::next() [Took: 0.008s] 
amarok: BEGIN: void Playlist::Actions::next() 
amarok:   BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:   END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:     BEGIN: virtual void Playlist::RandomTrackNavigator::planOne() 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     END__: virtual void Playlist::RandomTrackNavigator::planOne() [Took: 0s] 
amarok:   END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) [Took: 0s] 
amarok:   BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:     BEGIN: void EngineController::play(Meta::TrackPtr, uint, bool) 
amarok:       BEGIN: void EngineController::stop(bool, bool) 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST               1 
0:09:17.740333824 16244 0x7fead8003f20 WARN                typefind gsttypefindelement.c:1152:gst_type_find_element_loop:<typefind> error: stream stopped, reason error
PHONON-GST               1 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.039s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.039s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0.039s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "" )  
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
amarok:       END__: void EngineController::stop(bool, bool) [Took: 0.039s] 
amarok:       [EngineController] play: bounded is  QObject(0x0)  current "Tom_Ferro-Steel_Drum" 
amarok:       [EngineController] Just a normal, boring track... :-P 
amarok:       BEGIN: void EngineController::playUrl(const KUrl&, uint, bool) 
amarok:         [EngineController] URL:  KUrl("file:///home/se/music/_-_Tom_Ferro-Steel_Drum.ogg") "file:///home/se/music/_-_Tom_Ferro-Steel_Drum.ogg" 
amarok:         [EngineController] Offset:  0 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "file:///home/se/music/_-_Tom_Ferro-Steel_Drum.ogg" )  
PHONON-GST           uri "file:///home/se/music/_-_Tom_Ferro-Steel_Drum.ogg" 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
PHONON-GST         Fading to 1 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::play() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::PlayingState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "playing" 
0:09:17.743530864 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST               BEGIN: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) 
PHONON-GST               END__: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) [Took: 0s] 
0:09:17.743739368 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.002s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.002s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::play() [Took: 0.002s] 
amarok:       END__: void EngineController::playUrl(const KUrl&, uint, bool) [Took: 0.002s] 
amarok:     END__: void EngineController::play(Meta::TrackPtr, uint, bool) [Took: 0.042s] 
amarok:   END__: void Playlist::Actions::play(quint64, bool) [Took: 0.042s] 
amarok: END__: void Playlist::Actions::next() [Took: 0.042s] 
amarok: BEGIN: void Playlist::Actions::next() 
amarok:   BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:   END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:     BEGIN: virtual void Playlist::RandomTrackNavigator::planOne() 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     END__: virtual void Playlist::RandomTrackNavigator::planOne() [Took: 0s] 
amarok:   END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) [Took: 0s] 
amarok:   BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:     BEGIN: void EngineController::play(Meta::TrackPtr, uint, bool) 
amarok:       BEGIN: void EngineController::stop(bool, bool) 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST               1 
0:09:17.765032944 16244 0x7fead803d320 WARN                typefind gsttypefindelement.c:1152:gst_type_find_element_loop:<typefind> error: stream stopped, reason error
PHONON-GST               1 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.02s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.021s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0.021s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "" )  
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
amarok:       END__: void EngineController::stop(bool, bool) [Took: 0.021s] 
amarok:       [EngineController] play: bounded is  QObject(0x0)  current "08. Make You Feel Better" 
amarok:       [EngineController] Just a normal, boring track... :-P 
amarok:       BEGIN: void EngineController::playUrl(const KUrl&, uint, bool) 
amarok:         [EngineController] URL:  KUrl("file:///home/se/music/_-_08._Make_You_Feel_Better.ogg") "file:///home/se/music/_-_08._Make_You_Feel_Better.ogg" 
amarok:         [EngineController] Offset:  0 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "file:///home/se/music/_-_08._Make_You_Feel_Better.ogg" )  
PHONON-GST           uri "file:///home/se/music/_-_08._Make_You_Feel_Better.ogg" 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
PHONON-GST         Fading to 1 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::play() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::PlayingState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "playing" 
0:09:17.767631810 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST               BEGIN: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) 
PHONON-GST               END__: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) [Took: 0s] 
0:09:17.767864264 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.001s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.001s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::play() [Took: 0.001s] 
amarok:       END__: void EngineController::playUrl(const KUrl&, uint, bool) [Took: 0.002s] 
amarok:     END__: void EngineController::play(Meta::TrackPtr, uint, bool) [Took: 0.023s] 
amarok:   END__: void Playlist::Actions::play(quint64, bool) [Took: 0.023s] 
amarok: END__: void Playlist::Actions::next() [Took: 0.023s] 
amarok: BEGIN: void Playlist::Actions::next() 
amarok:   BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:   END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:     BEGIN: virtual void Playlist::RandomTrackNavigator::planOne() 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     END__: virtual void Playlist::RandomTrackNavigator::planOne() [Took: 0s] 
amarok:   END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::NonlinearTrackNavigator::setCurrentItem(quint64, bool) [Took: 0s] 
amarok:   BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:     BEGIN: void EngineController::play(Meta::TrackPtr, uint, bool) 
amarok:       BEGIN: void EngineController::stop(bool, bool) 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
0:09:17.774636650 16244 0x7fead803d000 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
0:09:17.774878389 16244 0x7feadc0f2a80 WARN                oggdemux gstoggdemux.c:2349:gst_ogg_demux_get_data:<oggdemux4> got -2 (flushing) from pull range
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.005s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.006s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0.006s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::stop() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::StoppedState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "ready" 
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::stop() [Took: 0s] 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "" )  
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
amarok:       END__: void EngineController::stop(bool, bool) [Took: 0.006s] 
amarok:       [EngineController] play: bounded is  QObject(0x0)  current "09._Eyes_on_Fire" 
amarok:       [EngineController] Just a normal, boring track... :-P 
amarok:       BEGIN: void EngineController::playUrl(const KUrl&, uint, bool) 
amarok:         [EngineController] URL:  KUrl("file:///home/se/music/_-_09._Eyes_on_Fire.ogg") "file:///home/se/music/_-_09._Eyes_on_Fire.ogg" 
amarok:         [EngineController] Offset:  0 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-GST           Setting new source 
PHONON-GST           New source:  QUrl( "file:///home/se/music/_-_09._Eyes_on_Fire.ogg" )  
PHONON-GST           uri "file:///home/se/music/_-_09._Eyes_on_Fire.ogg" 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::setSource(const Phonon::MediaSource&) [Took: 0s] 
PHONON-GST         Fading to 1 
PHONON-GST         BEGIN: virtual void Phonon::Gstreamer::MediaObject::play() 
PHONON-GST           BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST              Phonon::PlayingState  
PHONON-GST             BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST               Transitioning to state "playing" 
0:09:17.778123145 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST               BEGIN: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) 
PHONON-GST               END__: static void Phonon::Gstreamer::Pipeline::cb_setupSource(GstElement*, GParamSpec*, gpointer) [Took: 0s] 
0:09:17.778361950 16244       0xfba200 WARN                 basesrc gstbasesrc.c:3470:gst_base_src_start_complete:<source> pad not activated yet
PHONON-GST             END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.001s] 
PHONON-GST           END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.001s] 
PHONON-GST         END__: virtual void Phonon::Gstreamer::MediaObject::play() [Took: 0.002s] 
amarok:       END__: void EngineController::playUrl(const KUrl&, uint, bool) [Took: 0.002s] 
amarok:     END__: void EngineController::play(Meta::TrackPtr, uint, bool) [Took: 0.009s] 
amarok:   END__: void Playlist::Actions::play(quint64, bool) [Took: 0.009s] 
amarok: END__: void Playlist::Actions::next() [Took: 0.01s] 
PHONON-GST Stream changed to file:///home/se/music/_-_tom_swoon_lush_simon-ahead_of_us_e.ogg 
amarok: [EngineController] slotStateChanged from  Phonon::PlayingState  to  Phonon::PausedState  
amarok: MPRIS2: Queueing up a PropertiesChanged signal 
amarok: [EngineController] slotTrackLengthChanged( -1 ) 
amarok: BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok: END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok: [EngineController] slotStateChanged from  Phonon::PausedState  to  Phonon::StoppedState  
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) 
PHONON-GST   "Could not determine type of stream." 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) 
PHONON-GST   "GStreamer encountered a general stream error." 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) 
PHONON-GST   "Could not determine type of stream." 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) 
PHONON-GST   "GStreamer encountered a general stream error." 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::setError(const QString&, Phonon::ErrorType) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::handleStreamChange() 
PHONON-GST   false 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::handleStreamChange() [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST    Phonon::ErrorState  
PHONON-GST   BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST     Transitioning to state "null" 
PHONON-GST   END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0.007s] 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0.007s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST    Phonon::ErrorState  
PHONON-GST   BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST     Transitioning to state "null" 
PHONON-GST   END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST    Phonon::ErrorState  
PHONON-GST   BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST     Transitioning to state "null" 
PHONON-GST   END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
PHONON-GST BEGIN: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) 
PHONON-GST    Phonon::ErrorState  
PHONON-GST   BEGIN: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) 
PHONON-GST     Transitioning to state "null" 
PHONON-GST   END__: GstStateChangeReturn Phonon::Gstreamer::Pipeline::setState(GstState) [Took: 0s] 
PHONON-GST END__: void Phonon::Gstreamer::MediaObject::requestState(Phonon::State) [Took: 0s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged(): new meta-data: QMap(("xesam:title", QVariant(QString, "09._Eyes_on_Fire") ) ( "xesam:url" ,  QVariant(QUrl, QUrl("file:///home/se/music/_-_09._Eyes_on_Fire.ogg") ) ) )  
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:   [EngineController] Using gain of -1 with relative peak of -1 
PHONON-GST   Fading to 0.891251 
amarok:   BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::slotActiveTrackChanged(quint64) 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:       BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:       END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     END__: void Playlist::NonlinearTrackNavigator::slotActiveTrackChanged(quint64) [Took: 0s] 
amarok:     MPRIS2: Queueing up a PropertiesChanged signal 
amarok:     BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:       BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:       END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0.14s] 
amarok:     END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0.14s] 
amarok:   END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0.14s] 
amarok:   BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:     found  0  timecodes on this track 
amarok:   END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:   BEGIN: void CurrentEngine::trackPlaying(Meta::TrackPtr) 
amarok:     [CurrentEngine] updating track "09._Eyes_on_Fire" 
amarok:   END__: void CurrentEngine::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:   BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:   END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0s] 
amarok: END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0.15s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged(): new meta-data: QMap(("xesam:url", QVariant(QUrl, QUrl("file:///home/se/music/_-_09._Eyes_on_Fire.ogg") ) ) )  
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: BEGIN: void EngineController::slotMetaDataChanged() 
amarok:   [EngineController] slotMetaDataChanged() triggered by phonon, but we've already seen exactly the same metadata recently. Ignoring for now. 
amarok: END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
amarok: BEGIN: void ScriptManager::notifyFetchLyrics(const QString&, const QString&) 
amarok:   SCRIPT "LyricWiki" :  "request URL: http://lyrics.wikia.com/api.php?action=query&prop=revisions&rvprop=content&format=xml&titles=:09._Eyes_on_Fire" 
amarok:   BEGIN: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) 
amarok:   END__: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) [Took: 0s] 
amarok: END__: void ScriptManager::notifyFetchLyrics(const QString&, const QString&) [Took: 0s] 
amarok: MPRIS2: Album image location is KUrl("") 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok: BEGIN: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() 
amarok:   BEGIN: virtual void Playlist::RandomTrackNavigator::planOne() 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:     BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok:     END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
amarok:   END__: virtual void Playlist::RandomTrackNavigator::planOne() [Took: 0s] 
amarok: END__: Playlist::ItemList* Playlist::NonlinearTrackNavigator::nextItemChooseDonorList() [Took: 0s] 
amarok: BEGIN: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() 
amarok: END__: void Playlist::NonlinearTrackNavigator::doItemListsMaintenance() [Took: 0s] 
"PulseSupport(2): Phonon Output Stream {d6387678-8f95-437a-8517-d0e6423ff453} is gone at the PA end. Marking it as invalid in our cache as we may reuse it." 
amarok: [CoverFetcher] Finished due to cover not found for album "Fusion Festival 2014" 
amarok: [NetworkAccessManagerProxy] the server is redirecting the request to:  KUrl("http://lyrics.wikia.com/api.php?action=query&prop=revisions&rvprop=content&format=xml&titles=:09._Eyes_on_Fire&*") 
amarok: BEGIN: void AmarokDownloadHelper::requestRedirected(const KUrl&, const KUrl&) 
amarok: END__: void AmarokDownloadHelper::requestRedirected(const KUrl&, const KUrl&) [Took: 0s] 
amarok: BEGIN: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) 
amarok: END__: static QScriptValue Downloader::init(QScriptContext*, QScriptEngine*, bool) [Took: 0s] 
amarok: [NetworkAccessManagerProxy] the server is redirecting the request to:  KUrl("http://lyrics.wikia.com/api.php?action=lyrics&func=getSong&fmt=xml&artist=&song=09._Eyes_on_Fire&*") 
amarok: BEGIN: void AmarokDownloadHelper::requestRedirected(const KUrl&, const KUrl&) 
amarok: END__: void AmarokDownloadHelper::requestRedirected(const KUrl&, const KUrl&) [Took: 0s] 
amarok: BEGIN: void AmarokScript::AmarokLyricsScript::showLyricsNotFound(const QString&) const 
amarok:   BEGIN: void LyricsManager::lyricsNotFound(const QString&) 
amarok:     BEGIN: bool LyricsManager::showCached() 
amarok:     END__: bool LyricsManager::showCached() [Took: 0s] 
amarok:     BEGIN: void LyricsSubject::sendLyricsMessage(const QString&, const QString&) 
amarok:       BEGIN: virtual void LyricsEngine::lyricsMessage(const QString&, const QString&) 
amarok:       END__: virtual void LyricsEngine::lyricsMessage(const QString&, const QString&) [Took: 0s] 
amarok:     END__: void LyricsSubject::sendLyricsMessage(const QString&, const QString&) [Took: 0s] 
amarok:   END__: void LyricsManager::lyricsNotFound(const QString&) [Took: 0s] 
amarok: END__: void AmarokScript::AmarokLyricsScript::showLyricsNotFound(const QString&) const [Took: 0.001s] 
Calling appendChild() on a null node does nothing.
amarok: [CoverFetcher] Queueing automatic cover fetch for: "music" 
amarok: [CoverFetcher] Queueing automatic cover fetch for: "Nexus Music - All Music" 
amarok: [CoverFetchUnit] "Error occurred when preparing Last.fm urls for music: Namespace prefix 'opensearch' not declared" 
amarok: [CoverFetchUnit] QHash() 
amarok: [CoverFetcher] Finished due to cover not found for album "music" 
amarok: [CoverFetchUnit] "Error occurred when preparing Last.fm urls for Nexus Music - All Music: Namespace prefix 'opensearch' not declared" 
amarok: [CoverFetchUnit] QHash() 
amarok: [CoverFetcher] Finished due to cover not found for album "Nexus Music - All Music"

Comment 5 Myriam Schweingruber 2015-11-04 01:24:22 UTC

We really only need the last 30 lines or so when the playback stops despite having more tracks in the playlist. Please do not make anything else and wait for the bug to happen, hittin next is probably not the normal behavior. We can wait for the output, no hurry :)

Comment 6 sedrubal 2015-11-05 00:41:01 UTC

Maybe this is related to bug 354873 as in both cases "Calling appendChild() on a null node does nothing." is printed to stdout. But I'm not sure.

Comment 7 Myriam Schweingruber 2016-02-10 19:00:22 UTC

any news on this?

Comment 8 sedrubal 2016-02-14 16:53:18 UTC

I didn't expect this any more in the last view months... Should we close this?

Comment 9 Myriam Schweingruber 2016-02-14 23:04:19 UTC

(In reply to sedrubal from comment #8)
> I didn't expect this any more in the last view months... Should we close
> this?

Yes, you can still reopen if you experience it again, thank you for the feedback.