Bug 263123

Summary:	Amarok crashes when playing a streamed podcast using phonon-vlc [@ Phonon::VLC::Backend::startConnectionChange]
Product:	[Frameworks and Libraries] phonon-backend-vlc	Reporter:	Valorie Zimmerman <valorie.zimmerman>
Component:	general	Assignee:	Harald Sitter <sitter>
Status:	RESOLVED UPSTREAM
Severity:	crash	CC:	alex239, bcooksley, fabo, jb, marius.trandafir, martin.sandsmark, myriam, sven.burmeister, upscope
Priority:	NOR
Version:	unspecified
Target Milestone:	---
Platform:	Ubuntu
OS:	Linux
Latest Commit:		Version Fixed In:
Sentry Crash Report:

Description Valorie Zimmerman 2011-01-14 11:14:13 UTC

Application: amarok (2.4-GIT)
KDE Platform Version: 4.5.95 (4.6 RC2)
Qt Version: 4.7.0
Operating System: Linux 2.6.35-24-generic x86_64
Distribution: Ubuntu 10.10

-- Information about the crash:
- What I was doing when the application crashed: Testing out stream playing in latest git of Amarok, phonon, and phonon-vlc

- Custom settings of the application: newest phonon-vlc backend

-- Backtrace:
Application: Amarok (amarok), signal: Segmentation fault
[Current thread is 1 (Thread 0x7fb6966ee780 (LWP 4817))]

Thread 8 (Thread 0x7fb66c54d700 (LWP 4822)):
#0  0x00007fb693127203 in __poll (fds=<value optimized out>, nfds=<value optimized out>, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:87
#1  0x00007fb68c88c009 in ?? () from /lib/libglib-2.0.so.0
#2  0x00007fb68c88c45c in g_main_context_iteration () from /lib/libglib-2.0.so.0
#3  0x00007fb693a811e6 in QEventDispatcherGlib::processEvents (this=0x1f51b90, flags=<value optimized out>) at kernel/qeventdispatcher_glib.cpp:417
#4  0x00007fb693a53a02 in QEventLoop::processEvents (this=<value optimized out>, flags=) at kernel/qeventloop.cpp:149
#5  0x00007fb693a53dec in QEventLoop::exec (this=0x7fb66c54cd90, flags=) at kernel/qeventloop.cpp:201
#6  0x00007fb69395e2fd in QThread::exec (this=<value optimized out>) at thread/qthread.cpp:490
#7  0x00007fb693a335f8 in QInotifyFileSystemWatcherEngine::run (this=0x1f22680) at io/qfilesystemwatcher_inotify.cpp:248
#8  0x00007fb69396127e in QThreadPrivate::start (arg=0x1f22680) at thread/qthread_unix.cpp:266
#9  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#10 0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#11 0x0000000000000000 in ?? ()

Thread 7 (Thread 0x7fb66ad4b700 (LWP 4823)):
#0  __pthread_mutex_unlock (mutex=0x1f5ecb8) at pthread_mutex_unlock.c:289
#1  0x00007fb68c88bffc in ?? () from /lib/libglib-2.0.so.0
#2  0x00007fb68c88c45c in g_main_context_iteration () from /lib/libglib-2.0.so.0
#3  0x00007fb693a811e6 in QEventDispatcherGlib::processEvents (this=0x1f95620, flags=<value optimized out>) at kernel/qeventdispatcher_glib.cpp:417
#4  0x00007fb693a53a02 in QEventLoop::processEvents (this=<value optimized out>, flags=) at kernel/qeventloop.cpp:149
#5  0x00007fb693a53dec in QEventLoop::exec (this=0x7fb66ad4adb0, flags=) at kernel/qeventloop.cpp:201
#6  0x00007fb69395e2fd in QThread::exec (this=<value optimized out>) at thread/qthread.cpp:490
#7  0x00007fb68a0c9b6c in Herqq::Upnp::HControlPointThread::run() () from /usr/lib/libHUpnp.so.0
#8  0x00007fb69396127e in QThreadPrivate::start (arg=0x1f62730) at thread/qthread_unix.cpp:266
#9  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#10 0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#11 0x0000000000000000 in ?? ()

Thread 6 (Thread 0x7fb660d17700 (LWP 4832)):
#0  pthread_cond_wait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_wait.S:162
#1  0x00007fb68f4ad334 in QTWTF::TCMalloc_PageHeap::scavengerThread (this=0x7fb68f7b8160) at ../3rdparty/javascriptcore/JavaScriptCore/wtf/FastMalloc.cpp:2359
#2  0x00007fb68f4ad369 in QTWTF::TCMalloc_PageHeap::runScavengerThread (context=0x7fb68f7c6234) at ../3rdparty/javascriptcore/JavaScriptCore/wtf/FastMalloc.cpp:1464
#3  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#4  0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#5  0x0000000000000000 in ?? ()

Thread 5 (Thread 0x7fb64f288700 (LWP 4836)):
#0  0x00007fb693127203 in __poll (fds=<value optimized out>, nfds=<value optimized out>, timeout=-1) at ../sysdeps/unix/sysv/linux/poll.c:87
#1  0x00007fb68c88c009 in ?? () from /lib/libglib-2.0.so.0
#2  0x00007fb68c88c45c in g_main_context_iteration () from /lib/libglib-2.0.so.0
#3  0x00007fb693a811e6 in QEventDispatcherGlib::processEvents (this=0x3e77b50, flags=<value optimized out>) at kernel/qeventdispatcher_glib.cpp:417
#4  0x00007fb693a53a02 in QEventLoop::processEvents (this=<value optimized out>, flags=) at kernel/qeventloop.cpp:149
#5  0x00007fb693a53dec in QEventLoop::exec (this=0x7fb64f287d90, flags=) at kernel/qeventloop.cpp:201
#6  0x00007fb69395e2fd in QThread::exec (this=<value optimized out>) at thread/qthread.cpp:490
#7  0x00007fb693a335f8 in QInotifyFileSystemWatcherEngine::run (this=0x256ecf0) at io/qfilesystemwatcher_inotify.cpp:248
#8  0x00007fb69396127e in QThreadPrivate::start (arg=0x256ecf0) at thread/qthread_unix.cpp:266
#9  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#10 0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#11 0x0000000000000000 in ?? ()

Thread 4 (Thread 0x7fb65195b700 (LWP 5263)):
#0  pthread_cond_wait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_wait.S:162
#1  0x00007fb693961e3b in wait (this=<value optimized out>, mutex=0x7fc3d18, time=18446744073709551615) at thread/qwaitcondition_unix.cpp:88
#2  QWaitCondition::wait (this=<value optimized out>, mutex=0x7fc3d18, time=18446744073709551615) at thread/qwaitcondition_unix.cpp:160
#3  0x00007fb69496a10e in QFileInfoGatherer::run (this=<value optimized out>) at dialogs/qfileinfogatherer.cpp:214
#4  0x00007fb69396127e in QThreadPrivate::start (arg=0x7fc3d08) at thread/qthread_unix.cpp:266
#5  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#6  0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#7  0x0000000000000000 in ?? ()

Thread 3 (Thread 0x7fb65295d700 (LWP 5264)):
#0  pthread_mutex_unlock (mutex=0x7fd2238) at forward.c:184
#1  0x00007fb68c88ba05 in g_main_context_prepare () from /lib/libglib-2.0.so.0
#2  0x00007fb68c88be78 in ?? () from /lib/libglib-2.0.so.0
#3  0x00007fb68c88c45c in g_main_context_iteration () from /lib/libglib-2.0.so.0
#4  0x00007fb693a811e6 in QEventDispatcherGlib::processEvents (this=0x7fc9ca0, flags=<value optimized out>) at kernel/qeventdispatcher_glib.cpp:417
#5  0x00007fb693a53a02 in QEventLoop::processEvents (this=<value optimized out>, flags=) at kernel/qeventloop.cpp:149
#6  0x00007fb693a53dec in QEventLoop::exec (this=0x7fb65295cd90, flags=) at kernel/qeventloop.cpp:201
#7  0x00007fb69395e2fd in QThread::exec (this=<value optimized out>) at thread/qthread.cpp:490
#8  0x00007fb693a335f8 in QInotifyFileSystemWatcherEngine::run (this=0x7fc4d50) at io/qfilesystemwatcher_inotify.cpp:248
#9  0x00007fb69396127e in QThreadPrivate::start (arg=0x7fc4d50) at thread/qthread_unix.cpp:266
#10 0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#11 0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#12 0x0000000000000000 in ?? ()

Thread 2 (Thread 0x7fb673cdf700 (LWP 5269)):
#0  pthread_cond_wait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_wait.S:162
#1  0x00007fb67748d272 in Thread () from /usr/lib/libvlccore.so.4
#2  0x00007fb68eba9971 in start_thread (arg=<value optimized out>) at pthread_create.c:304
#3  0x00007fb69313392d in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:112
#4  0x0000000000000000 in ?? ()

Thread 1 (Thread 0x7fb6966ee780 (LWP 4817)):
[KCrash Handler]
#6  0x00007fb677994bb1 in Phonon::VLC::Backend::startConnectionChange (this=0x1cb5700, objects=...) at /home/valorie/kde/src/phonon-vlc/vlc/backend.cpp:382
#7  0x00007fb69019cdad in Phonon::ConnectionTransaction::ConnectionTransaction (this=0x7fff3b9362e0, b=0x1cb5710, x=...) at /home/valorie/kde/src/phonon/phonon/path.cpp:42
#8  0x00007fb69019bdac in Phonon::PathPrivate::executeTransaction (this=0x1e93d00, disconnections=..., connections=...) at /home/valorie/kde/src/phonon/phonon/path.cpp:309
#9  0x00007fb69019ba35 in Phonon::Path::disconnect (this=0x1c0fae8) at /home/valorie/kde/src/phonon/phonon/path.cpp:253
#10 0x00007fb6955641be in EngineController::initializePhonon (this=0x1c0fa50) at /home/valorie/kde/src/amarok/src/EngineController.cpp:138
#11 0x00007fb69556b1fc in EngineController::slotStateChanged (this=0x1c0fa50, newState=Phonon::ErrorState, oldState=Phonon::PlayingState) at /home/valorie/kde/src/amarok/src/EngineController.cpp:1154
#12 0x00007fb69556eef7 in EngineController::qt_metacall (this=0x1c0fa50, _c=QMetaObject::InvokeMetaMethod, _id=41, _a=0x7fff3b936830) at /home/valorie/kde/build/amarok/src/EngineController.moc:206
#13 0x00007fb693a6cb27 in QMetaObject::activate (sender=0x1bc38a0, m=<value optimized out>, local_signal_index=<value optimized out>, argv=0x1cb5710) at kernel/qobject.cpp:3280
#14 0x00007fb6901976fb in Phonon::MediaObject::stateChanged (this=0x1bc38a0, _t1=Phonon::ErrorState, _t2=Phonon::PlayingState) at /home/valorie/kde/src/phonon/build/phonon/moc_mediaobject.cpp:184
#15 0x00007fb69019733d in Phonon::MediaObject::qt_metacall (this=0x1bc38a0, _c=QMetaObject::InvokeMetaMethod, _id=0, _a=0x85bdf30) at /home/valorie/kde/src/phonon/build/phonon/moc_mediaobject.cpp:120
#16 0x00007fb693a66bde in QObject::event (this=0x1bc38a0, e=0x7fff3b9361b7) at kernel/qobject.cpp:1219
#17 0x00007fb694436fdc in QApplicationPrivate::notify_helper (this=0x1b37b90, receiver=0x1bc38a0, e=0x1bb7a00) at kernel/qapplication.cpp:4396
#18 0x00007fb69443caed in QApplication::notify (this=0x7fff3b9372b0, receiver=0x1bc38a0, e=0x1bb7a00) at kernel/qapplication.cpp:4277
#19 0x00007fb6960f2a36 in KApplication::notify (this=0x7fff3b9372b0, receiver=0x1bc38a0, event=0x1bb7a00) at ../../kdeui/kernel/kapplication.cpp:311
#20 0x00007fb693a54cdc in QCoreApplication::notifyInternal (this=0x7fff3b9372b0, receiver=0x1bc38a0, event=0x1bb7a00) at kernel/qcoreapplication.cpp:732
#21 0x00007fb693a57c22 in sendEvent (receiver=0x0, event_type=<value optimized out>, data=0x19ad660) at ../../include/QtCore/../../src/corelib/kernel/qcoreapplication.h:215
#22 QCoreApplicationPrivate::sendPostedEvents (receiver=0x0, event_type=<value optimized out>, data=0x19ad660) at kernel/qcoreapplication.cpp:1373
#23 0x00007fb693a81653 in sendPostedEvents (s=0x1b3b5d0) at ../../include/QtCore/../../src/corelib/kernel/qcoreapplication.h:220
#24 postEventSourceDispatch (s=0x1b3b5d0) at kernel/qeventdispatcher_glib.cpp:277
#25 0x00007fb68c888342 in g_main_context_dispatch () from /lib/libglib-2.0.so.0
#26 0x00007fb68c88c2a8 in ?? () from /lib/libglib-2.0.so.0
#27 0x00007fb68c88c45c in g_main_context_iteration () from /lib/libglib-2.0.so.0
#28 0x00007fb693a81193 in QEventDispatcherGlib::processEvents (this=0x19acd10, flags=<value optimized out>) at kernel/qeventdispatcher_glib.cpp:415
#29 0x00007fb6944e9a4e in QGuiEventDispatcherGlib::processEvents (this=0x7fff3b9361b7, flags=<value optimized out>) at kernel/qguieventdispatcher_glib.cpp:204
#30 0x00007fb693a53a02 in QEventLoop::processEvents (this=<value optimized out>, flags=) at kernel/qeventloop.cpp:149
#31 0x00007fb693a53dec in QEventLoop::exec (this=0x7fff3b937250, flags=) at kernel/qeventloop.cpp:201
#32 0x00007fb693a57ebb in QCoreApplication::exec () at kernel/qcoreapplication.cpp:1009
#33 0x000000000040cdb7 in main (argc=3, argv=0x7fff3b9397d8) at /home/valorie/kde/src/amarok/src/main.cpp:271

Reported using DrKonqi

Comment 1 Myriam Schweingruber 2011-01-22 14:13:55 UTC

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

Comment 2 Harald Sitter 2011-03-29 10:19:04 UTC

Are you still able to reproduce this issue? Because the crash does not make sense at all, I have no idea how it could crash there.

Comment 3 Myriam Schweingruber 2011-03-29 15:53:40 UTC

Setting status correctly.

Comment 4 Valorie Zimmerman 2011-03-30 09:35:29 UTC

OK, freshly build Amarok, Phonon, VLC, and phonon-vlc, and a non-downloaded podcast. No crash, but no podcast either. Output:

amarok: BEGIN: void BrowserCategory::activate() 
amarok:   BEGIN: void BrowserCategoryList::activate(BrowserCategory*) 
amarok:     BEGIN: void BrowserCategoryList::showCategory(const QString&) 
amarok:       BEGIN: void BrowserBreadcrumbWidget::updateBreadcrumbs() 
amarok:         BEGIN: void BrowserBreadcrumbWidget::addLevel(BrowserCategoryList*) 
amarok:         END__: void BrowserBreadcrumbWidget::addLevel(BrowserCategoryList*) [Took: 0.006s] 
amarok:       END__: void BrowserBreadcrumbWidget::updateBreadcrumbs() [Took: 0.009s] 
amarok:     END__: void BrowserCategoryList::showCategory(const QString&) [Took: 0.026s] 
amarok:   END__: void BrowserCategoryList::activate(BrowserCategory*) [Took: 0.026s] 
amarok: END__: void BrowserCategory::activate() [Took: 0.026s] 
amarok: BEGIN: InfoProxy::InfoProxy() 
amarok:   BEGIN: void InfoProxy::loadHomePage() 
amarok:   END__: void InfoProxy::loadHomePage() [Took: 0.022s] 
amarok: END__: InfoProxy::InfoProxy() [Took: 0.023s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.005s] 
amarok: BEGIN: void Playlist::Controller::insertOptioned(Meta::TrackList, int) 
amarok:   [Playlist::Controller] Append 
amarok:   BEGIN: int Playlist::Controller::insertionTopRowToBottom(int) 
amarok:     [Playlist::Controller] SortProxy is NOT SORTED         ... so I'll take care of the right row. 
amarok:   END__: int Playlist::Controller::insertionTopRowToBottom(int) [Took: 0s] 
amarok:   BEGIN: virtual void Playlist::InsertTracksCmd::redo() 
amarok:     BEGIN: void Playlist::Model::setAllNewlyAddedToUnplayed() 
amarok:     END__: void Playlist::Model::setAllNewlyAddedToUnplayed() [Took: 0s] 
amarok:     BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:     END__: void StatusBar::updateTotalPlaylistLength() [Took: 0.003s] 
amarok:   END__: virtual void Playlist::InsertTracksCmd::redo() [Took: 0.004s] 
amarok:   [Playlist::Controller] engine playing?:  false 
amarok:   BEGIN: void Playlist::Actions::play(int) 
amarok:     BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:       BEGIN: void EngineController::play(Meta::TrackPtr, uint) 
amarok:         BEGIN: void EngineController::stop(bool) 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC           virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC           [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:           BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:             [EngineController] Empty MediaSource (engine stop) 
amarok:           END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok:         END__: void EngineController::stop(bool) [Took: 0.002s] 
amarok:         [EngineController] play: bounded is  QObject(0x0)  current "Genealogy: Ancestors on the Move" 
amarok:         [EngineController] Just a normal, boring track... :-P 
amarok:         BEGIN: void EngineController::playUrl(const KUrl&, uint) 
amarok:           [EngineController] URL:  "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:           [EngineController] offset:  0 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 1  QUrl( "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" )  "" 
PHONON-VLC           virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC           virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) yeap, 'tis a local file or url "http" 
PHONON-VLC           BEGIN: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC               "StoppedState" --> "LoadingState" 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC             loading encoded: "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC               "LoadingState" --> "StoppedState" 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC           END__: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) [Took: 0s] 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:           BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:             [EngineController] Using gain of 0 with relative peak of 0 
PHONON-VLC             createObject() : VolumeFaderEffect not implemented 
PHONON-VLC             createObject() : VolumeFaderEffect not implemented 
amarok:             BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:               episode:  "Genealogy: Ancestors on the Move" 
amarok:             END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:             BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:             END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0.003s]                                                                                                         
amarok:             BEGIN: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) 
amarok:               [lastfm] track type: "mp3" 
amarok:               BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:                 [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:               END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:               [lastfm] nowPlaying:  "[unknown]"  -  "Digging Up Your Roots"  -  "Genealogy: Ancestors on the Move" 
HTTP POST:  QUrl( "http://post.audioscrobbler.com:80/np_1.2" )  "s=2650b8a6532340c59d805d0783282d06&a=%5Bunknown%5D&t=Genealogy%3A%20Ancestors%20on%20the%20Move&b=Digging%20Up%20Your%20Roots&l=0&n=0&m=" 
amarok:             END__: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:             BEGIN: void TimecodeObserver::trackPlaying(Meta::TrackPtr) 
amarok:               current track name:  "Genealogy: Ancestors on the Move" 
amarok:               Track timecodeable 
amarok:             END__: void TimecodeObserver::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:             BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:               BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:               END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:               BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:                 BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:                 END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:               END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:             END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:             BEGIN: void ProgressWidget::trackLengthChanged(qint64) 
amarok:               new length:  -1 
amarok:               slider enabled! 
amarok:               BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:                 BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                   episode:  "Genealogy: Ancestors on the Move" 
amarok:                 END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:                 BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:                 END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s]                                                                                                         
amarok:                 found  0  timecodes on this track 
amarok:               END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:             END__: void ProgressWidget::trackLengthChanged(qint64) [Took: 0.001s] 
amarok:             BEGIN: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) 
amarok:             END__: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:             BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:             END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.047s] 
amarok:           END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0.12s] 
PHONON-VLC           virtual void Phonon::VLC::MediaObject::play() 
PHONON-VLC           BEGIN: void Phonon::VLC::MediaObject::playInternal() 
amarok:             BEGIN: void EngineController::slotMetaDataChanged() 
amarok:               [EngineController] Artist     :  ("") 
amarok:               [EngineController] Album      :  ("") 
amarok:               [EngineController] Title      :  ("http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3") 
amarok:               [EngineController] Genre      :  ("") 
amarok:               [EngineController] Tracknumber:  ("") 
amarok:               [EngineController] Length     :  () 
amarok:               [EngineController] Track changed:  true current: 0x3fc23b8 url "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:               [EngineController] no spam 
amarok:             END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
Metadata ready, sending to zeitgeist 
[0x3948370] main input debug: Creating an input for 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x3948370] main input debug: thread (input) created at priority 10 (input/input.c:220)
[0x3948370] main input debug: thread started
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
[0x3948370] main input debug: using timeshift granularity of 50 MiB
PHONON-VLC               "StoppedState" --> "PlayingState" 
[0x3948370] main input debug: using timeshift path '/tmp'
PHONON-VLC             END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC           END__: void Phonon::VLC::MediaObject::playInternal() [Took: 0.002s] 
[0x3948370] main input debug: `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' gives access `http' demux `' path `downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x3948370] main input debug: creating demux: access='http' demux='' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
PHONON-VLC           Volume changed from 50 to 100 
[0x7f9a245ba0e0] main demux debug: looking for access_demux module: 0 candidates
[0x7f9a245ba0e0] main demux debug: no access_demux module matched "http"
[0x3948370] main input debug: creating access 'http' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a24580600] main access debug: looking for access module: 4 candidates
amarok:           [EngineController] track pos after play:  0 
amarok:         END__: void EngineController::playUrl(const KUrl&, uint) [Took: 0.12s] 
amarok:       END__: void EngineController::play(Meta::TrackPtr, uint) [Took: 0.13s] 
amarok:     END__: void Playlist::Actions::play(quint64, bool) [Took: 0.13s] 
amarok:   END__: void Playlist::Actions::play(int) [Took: 0.13s] 
amarok: END__: void Playlist::Controller::insertOptioned(Meta::TrackList, int) [Took: 0.14s] 
amarok: BEGIN: void Playlist::PrettyListView::bottomModelRowsInsertedScroll() 
amarok: END__: void Playlist::PrettyListView::bottomModelRowsInsertedScroll() [Took: 0s] 
State changed from 1 to 0 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 0 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.002s] 
State changed from 1 to 2 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
[0x7f9a24580600] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a24580600] access_http access debug: libproxy suggest to use 'direct://'
[0x7f9a24580600] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
"OK" 
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_http access debug: protocol 'HTTP' answer code 404
[0x7f9a24580600] access_http access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] access_http access debug: switching to HTTP version 1.0
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_http access debug: protocol 'HTTP' answer code 404
[0x7f9a24580600] access_http access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a24580600] access_http access debug: libproxy suggest to use 'direct://'
[0x7f9a24580600] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_http access debug: protocol 'HTTP' answer code 404
[0x7f9a24580600] access_http access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] access_http access debug: switching to HTTP version 1.0
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_http access debug: protocol 'HTTP' answer code 404
[0x7f9a24580600] access_http access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_mms access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x7f9a24580600] main access debug: connection succeeded (socket = 79)
[0x7f9a24580600] access_mms access error: error: HTTP/1.1 404 Not Found
[0x7f9a24580600] main access debug: no access module matching "http" could be loaded
[0x7f9a24580600] main access debug: waitpipe: object killed
[0x3948370] main input error: open of `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' failed: (null)                                                                           
[0x3948370] main input error: Your input can't be opened
[0x3948370] main input error: VLC is unable to open the MRL 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'. Check the log for details.                                        
[0x3948370] main input debug: thread ended
amarok: BEGIN: void EngineController::slotFinished() 
amarok:   BEGIN: void ScrobblerAdapter::stopped(qint64, qint64) 
amarok:     BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:       [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:     END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:   END__: void ScrobblerAdapter::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void TimecodeObserver::stopped(qint64, qint64) 
amarok:   END__: void TimecodeObserver::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:   END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.026s] 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC   BEGIN: virtual void Phonon::VLC::MediaObject::stop() 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "PlayingState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "StoppedState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC   END__: virtual void Phonon::VLC::MediaObject::stop() [Took: 0s] 
PHONON-VLC   virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC   [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:   BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:     [EngineController] Empty MediaSource (engine stop) 
amarok:   END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok: END__: void EngineController::slotFinished() [Took: 0.048s] 
PHONON-VLC BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC   "StoppedState" --> "ErrorState" 
PHONON-VLC END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
State changed from 2 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 1 to 5 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok:   [WARNING] [EngineController] Phonon failed to play this URL. Error:  "" 
amarok:   [WARNING] [EngineController] Forcing phonon engine reinitialization. 
amarok:   BEGIN: void EngineController::initializePhonon() 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
"PulseSupport(2): Clearing stream cache for stream {af3c47cc-dbab-425a-9353-e930a3fc0ac3}" 
"PulseSupport(2): Initialising streamindex {2c667636-a317-4d49-9cf0-c8806854a349}" 
"PulseSupport(2): Setting role to music for streamindex {2c667636-a317-4d49-9cf0-c8806854a349}" 
emitting a state change because the backend object has been replaced 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Backend connected Phonon::VLC::MediaObject to Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
void Phonon::FrontendInterfacePrivate::_backendObjectChanged() 
PHONON-VLC     createObject() : VolumeFaderEffect not implemented 
amarok:     [EngineController] Tick Interval (actual):  100 
amarok:   END__: void EngineController::initializePhonon() [Took: 0.006s] 
amarok:   BEGIN: void EngineController::slotAboutToFinish() 
amarok:     [EngineController] Track finished completely, updating statistics 
amarok:     BEGIN: void Playlist::Actions::requestNextTrack() 
amarok:       [Playlist::Actions] so far so good! 
amarok:       [Playlist::Actions] nothing more to play... 
amarok:       BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:         BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:         END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:       END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:       BEGIN: virtual void Playlist::Model::setAllUnplayed() 
amarok:         BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:         END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:       END__: virtual void Playlist::Model::setAllUnplayed() [Took: 0s] 
amarok:     END__: void Playlist::Actions::requestNextTrack() [Took: 0s] 
amarok:   END__: void EngineController::slotAboutToFinish() [Took: 0s] 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.007s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.007s] 
amarok: BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:   BEGIN: void DirWatchJob::setPaused(bool) 
amarok:   END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok: END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.008s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.007s] 
amarok: BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:   BEGIN: void DirWatchJob::setPaused(bool) 
amarok:   END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok: END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.008s] 
amarok: BEGIN: void EngineController::playPause() 
amarok:   [EngineController] PlayPause: EngineController state 1 
amarok:   BEGIN: void EngineController::play() 
amarok:     BEGIN: void Playlist::Actions::play() 
amarok:       BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:         BEGIN: void EngineController::play(Meta::TrackPtr, uint) 
amarok:           BEGIN: void EngineController::stop(bool) 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC             [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Empty MediaSource (engine stop) 
amarok:             END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok:           END__: void EngineController::stop(bool) [Took: 0s] 
amarok:           [EngineController] play: bounded is  QObject(0x0)  current "Genealogy: Ancestors on the Move" 
amarok:           [EngineController] Just a normal, boring track... :-P 
amarok:           BEGIN: void EngineController::playUrl(const KUrl&, uint) 
amarok:             [EngineController] URL:  "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:             [EngineController] offset:  0 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 1  QUrl( "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" )  "" 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) yeap, 'tis a local file or url "http" 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) 
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "StoppedState" --> "LoadingState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC               loading encoded: "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "LoadingState" --> "StoppedState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) [Took: 0s] 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Using gain of 0 with relative peak of 0 
PHONON-VLC               createObject() : VolumeFaderEffect not implemented 
PHONON-VLC               createObject() : VolumeFaderEffect not implemented 
amarok:               BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                 episode:  "Genealogy: Ancestors on the Move" 
amarok:               END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:               BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:               END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s]                                                                                                           
amarok:               BEGIN: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) 
amarok:                 [lastfm] track type: "mp3" 
amarok:                 BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:                   [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:                 END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:                 [lastfm] nowPlaying:  "[unknown]"  -  "Digging Up Your Roots"  -  "Genealogy: Ancestors on the Move" 
HTTP POST:  QUrl( "http://post.audioscrobbler.com:80/np_1.2" )  "s=2650b8a6532340c59d805d0783282d06&a=%5Bunknown%5D&t=Genealogy%3A%20Ancestors%20on%20the%20Move&b=Digging%20Up%20Your%20Roots&l=0&n=0&m=" 
amarok:               END__: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void TimecodeObserver::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void TimecodeObserver::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:                 BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:                 END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:                 BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:                   BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:                   END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:                 END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:               END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void ProgressWidget::trackLengthChanged(qint64) 
amarok:                 new length:  -1 
amarok:                 slider enabled! 
amarok:                 BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:                   BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                     episode:  "Genealogy: Ancestors on the Move" 
amarok:                   END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:                   BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:                   END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s]                                                                                                       
amarok:                   found  0  timecodes on this track 
amarok:                 END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:               END__: void ProgressWidget::trackLengthChanged(qint64) [Took: 0s] 
amarok:               BEGIN: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:               END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.019s] 
amarok:             END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0.065s]                                                                                                          
PHONON-VLC             virtual void Phonon::VLC::MediaObject::play() 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::playInternal() 
amarok:               BEGIN: void EngineController::slotMetaDataChanged() 
amarok:                 [EngineController] Artist     :  ("") 
amarok:                 [EngineController] Album      :  ("") 
amarok:                 [EngineController] Title      :  ("http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3") 
amarok:                 [EngineController] Genre      :  ("") 
amarok:                 [EngineController] Tracknumber:  ("") 
amarok:                 [EngineController] Length     :  () 
amarok:                 [EngineController] Track changed:  false current: 0x3fc23b8 url "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:               END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
Metadata ready, sending to zeitgeist 
[0x2dfded0] main input debug: Creating an input for 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2dfded0] main input debug: thread (input) created at priority 10 (input/input.c:220)
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "StoppedState" --> "PlayingState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::playInternal() [Took: 0.002s] 
PHONON-VLC             Volume changed from 50 to 100 
[0x1065bc0] main playlist debug: no fetch required for (null) (art currently (null))
[0x2dfded0] main input debug: thread started
[0x2dfded0] main input debug: using timeshift granularity of 50 MiB
[0x2dfded0] main input debug: using timeshift path '/tmp'
[0x2dfded0] main input debug: `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' gives access `http' demux `' path `downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2dfded0] main input debug: creating demux: access='http' demux='' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2ac8d10] main demux debug: looking for access_demux module: 0 candidates
[0x2ac8d10] main demux debug: no access_demux module matched "http"
[0x2dfded0] main input debug: creating access 'http' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x1cf2be0] main access debug: looking for access module: 4 candidates
amarok:             [EngineController] track pos after play:  0 
amarok:           END__: void EngineController::playUrl(const KUrl&, uint) [Took: 0.078s] 
amarok:         END__: void EngineController::play(Meta::TrackPtr, uint) [Took: 0.079s] 
amarok:       END__: void Playlist::Actions::play(quint64, bool) [Took: 0.079s] 
amarok:     END__: void Playlist::Actions::play() [Took: 0.079s] 
amarok:   END__: void EngineController::play() [Took: 0.079s] 
amarok: END__: void EngineController::playPause() [Took: 0.08s] 
State changed from 1 to 0 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 0 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 1 to 2 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
[0x1cf2be0] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x1cf2be0] access_http access debug: libproxy suggest to use 'direct://'
[0x1cf2be0] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
"OK" 
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_http access debug: protocol 'HTTP' answer code 404
[0x1cf2be0] access_http access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] access_http access debug: switching to HTTP version 1.0
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_http access debug: protocol 'HTTP' answer code 404
[0x1cf2be0] access_http access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x1cf2be0] access_http access debug: libproxy suggest to use 'direct://'
[0x1cf2be0] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_http access debug: protocol 'HTTP' answer code 404
[0x1cf2be0] access_http access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] access_http access debug: switching to HTTP version 1.0
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_http access debug: protocol 'HTTP' answer code 404
[0x1cf2be0] access_http access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_mms access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x1cf2be0] main access debug: connection succeeded (socket = 53)
[0x1cf2be0] access_mms access error: error: HTTP/1.1 404 Not Found
[0x1cf2be0] main access debug: no access module matching "http" could be loaded
[0x1cf2be0] main access debug: waitpipe: object killed
[0x2dfded0] main input error: open of `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' failed: (null)                                                                           
[0x2dfded0] main input error: Your input can't be opened
[0x2dfded0] main input error: VLC is unable to open the MRL 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'. Check the log for details.                                        
[0x2dfded0] main input debug: thread ended
amarok: BEGIN: void EngineController::slotFinished() 
amarok:   BEGIN: void ScrobblerAdapter::stopped(qint64, qint64) 
amarok:     BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:       [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:     END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:   END__: void ScrobblerAdapter::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void TimecodeObserver::stopped(qint64, qint64) 
amarok:   END__: void TimecodeObserver::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:   END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.035s] 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC   BEGIN: virtual void Phonon::VLC::MediaObject::stop() 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "PlayingState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "StoppedState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC   END__: virtual void Phonon::VLC::MediaObject::stop() [Took: 0s] 
PHONON-VLC   virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC   [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:   BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:     [EngineController] Empty MediaSource (engine stop) 
amarok:   END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok: END__: void EngineController::slotFinished() [Took: 0.055s] 
PHONON-VLC BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC   "StoppedState" --> "ErrorState" 
PHONON-VLC END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
State changed from 2 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 1 to 5 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok:   [WARNING] [EngineController] Phonon failed to play this URL. Error:  "" 
amarok:   [WARNING] [EngineController] Forcing phonon engine reinitialization. 
amarok:   BEGIN: void EngineController::initializePhonon() 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
"PulseSupport(2): Clearing stream cache for stream {2c667636-a317-4d49-9cf0-c8806854a349}" 
"PulseSupport(2): Initialising streamindex {88f4217d-6e18-4645-bce1-59429ebfea22}" 
"PulseSupport(2): Setting role to music for streamindex {88f4217d-6e18-4645-bce1-59429ebfea22}" 
emitting a state change because the backend object has been replaced 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Backend connected Phonon::VLC::MediaObject to Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
void Phonon::FrontendInterfacePrivate::_backendObjectChanged() 
PHONON-VLC     createObject() : VolumeFaderEffect not implemented 
amarok:     [EngineController] Tick Interval (actual):  100 
amarok:   END__: void EngineController::initializePhonon() [Took: 0.004s] 
amarok:   BEGIN: void EngineController::slotAboutToFinish() 
amarok:     [EngineController] Track finished completely, updating statistics 
amarok:     BEGIN: void Playlist::Actions::requestNextTrack() 
amarok:       [Playlist::Actions] so far so good! 
amarok:       [Playlist::Actions] nothing more to play... 
amarok:       BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:         BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:         END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:       END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:       BEGIN: virtual void Playlist::Model::setAllUnplayed() 
amarok:         BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:         END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:       END__: virtual void Playlist::Model::setAllUnplayed() [Took: 0s] 
amarok:     END__: void Playlist::Actions::requestNextTrack() [Took: 0s] 
amarok:   END__: void EngineController::slotAboutToFinish() [Took: 0s] 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.005s] 
amarok: BEGIN: void EngineController::playPause() 
amarok:   [EngineController] PlayPause: EngineController state 1 
amarok:   BEGIN: void EngineController::play() 
amarok:     BEGIN: void Playlist::Actions::play() 
amarok:       BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:         BEGIN: void EngineController::play(Meta::TrackPtr, uint) 
amarok:           BEGIN: void EngineController::stop(bool) 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC             [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Empty MediaSource (engine stop) 
amarok:             END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok:           END__: void EngineController::stop(bool) [Took: 0s] 
amarok:           [EngineController] play: bounded is  QObject(0x0)  current "Genealogy: Ancestors on the Move" 
amarok:           [EngineController] Just a normal, boring track... :-P 
amarok:           BEGIN: void EngineController::playUrl(const KUrl&, uint) 
amarok:             [EngineController] URL:  "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:             [EngineController] offset:  0 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 1  QUrl( "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" )  "" 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC             virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) yeap, 'tis a local file or url "http" 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) 
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "StoppedState" --> "LoadingState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC               loading encoded: "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "LoadingState" --> "StoppedState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::loadMedia(const QByteArray&) [Took: 0s] 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Using gain of 0 with relative peak of 0 
PHONON-VLC               createObject() : VolumeFaderEffect not implemented 
PHONON-VLC               createObject() : VolumeFaderEffect not implemented 
amarok:               BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                 episode:  "Genealogy: Ancestors on the Move" 
amarok:               END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:               BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:               END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0.001s]                                                                                                       
amarok:               BEGIN: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) 
amarok:                 [lastfm] track type: "mp3" 
amarok:                 BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:                   [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:                 END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:                 [lastfm] nowPlaying:  "[unknown]"  -  "Digging Up Your Roots"  -  "Genealogy: Ancestors on the Move" 
HTTP POST:  QUrl( "http://post.audioscrobbler.com:80/np_1.2" )  "s=2650b8a6532340c59d805d0783282d06&a=%5Bunknown%5D&t=Genealogy%3A%20Ancestors%20on%20the%20Move&b=Digging%20Up%20Your%20Roots&l=0&n=0&m=" 
amarok:               END__: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) [Took: 0.001s] 
amarok:               BEGIN: void TimecodeObserver::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void TimecodeObserver::trackPlaying(Meta::TrackPtr) [Took: 0.001s] 
amarok:               BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:                 BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:                 END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:                 BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:                   BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:                   END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:                 END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:               END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void ProgressWidget::trackLengthChanged(qint64) 
amarok:                 new length:  -1 
amarok:                 slider enabled! 
amarok:                 BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:                   BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                     episode:  "Genealogy: Ancestors on the Move" 
amarok:                   END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:                   BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:                   END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s]                                                                                                       
amarok:                   found  0  timecodes on this track 
amarok:                 END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:               END__: void ProgressWidget::trackLengthChanged(qint64) [Took: 0s] 
amarok:               BEGIN: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void Dynamic::EchoNestBias::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.029s]                                                                                                          
PHONON-VLC             virtual void Phonon::VLC::MediaObject::play() 
PHONON-VLC             BEGIN: void Phonon::VLC::MediaObject::playInternal() 
amarok:               BEGIN: void EngineController::slotMetaDataChanged() 
amarok:                 [EngineController] Artist     :  ("") 
amarok:                 [EngineController] Album      :  ("") 
amarok:                 [EngineController] Title      :  ("http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3") 
amarok:                 [EngineController] Genre      :  ("") 
amarok:                 [EngineController] Tracknumber:  ("") 
amarok:                 [EngineController] Length     :  () 
amarok:                 [EngineController] Track changed:  false current: 0x3fc23b8 url "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:               END__: void EngineController::slotMetaDataChanged() [Took: 0s] 
Metadata ready, sending to zeitgeist 
[0x7f9a245b9520] main input debug: Creating an input for 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a245b9520] main input debug: thread (input) created at priority 10 (input/input.c:220)
PHONON-VLC               BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC                 "StoppedState" --> "PlayingState" 
PHONON-VLC               END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC             END__: void Phonon::VLC::MediaObject::playInternal() [Took: 0.001s] 
PHONON-VLC             Volume changed from 50 to 100 
[0x7f9a245b9520] main input debug: thread started
[0x7f9a245b9520] main input debug: using timeshift granularity of 50 MiB
[0x7f9a245b9520] main input debug: using timeshift path '/tmp'
[0x7f9a245b9520] main input debug: `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' gives access `http' demux `' path `downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x7f9a245b9520] main input debug: creating demux: access='http' demux='' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x3fcca10] main demux debug: looking for access_demux module: 0 candidates
[0x3fcca10] main demux debug: no access_demux module matched "http"
[0x7f9a245b9520] main input debug: creating access 'http' path='downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2d2a360] main access debug: looking for access module: 4 candidates
amarok:             [EngineController] track pos after play:  0 
amarok:           END__: void EngineController::playUrl(const KUrl&, uint) [Took: 0.078s] 
amarok:         END__: void EngineController::play(Meta::TrackPtr, uint) [Took: 0.079s] 
amarok:       END__: void Playlist::Actions::play(quint64, bool) [Took: 0.079s] 
amarok:     END__: void Playlist::Actions::play() [Took: 0.08s] 
amarok:   END__: void EngineController::play() [Took: 0.08s] 
amarok: END__: void EngineController::playPause() [Took: 0.08s] 
State changed from 1 to 0 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 0 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.002s] 
State changed from 1 to 2 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.003s] 
[0x2d2a360] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2d2a360] access_http access debug: libproxy suggest to use 'direct://'
[0x2d2a360] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
"OK" 
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_http access debug: protocol 'HTTP' answer code 404
[0x2d2a360] access_http access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] access_http access debug: switching to HTTP version 1.0
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_http access debug: protocol 'HTTP' answer code 404
[0x2d2a360] access_http access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] access_http access debug: asking libproxy about url 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2d2a360] access_http access debug: libproxy suggest to use 'direct://'
[0x2d2a360] access_http access debug: http: server='downloads.bbc.co.uk' port=80 file='/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_http access debug: protocol 'HTTP' answer code 404
[0x2d2a360] access_http access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] access_http access debug: switching to HTTP version 1.0
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_http access debug: protocol 'HTTP' answer code 404
[0x2d2a360] access_http access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_mms access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] main access debug: net: connecting to downloads.bbc.co.uk port 80
[0x2d2a360] main access debug: connection succeeded (socket = 53)
[0x2d2a360] access_mms access error: error: HTTP/1.1 404 Not Found
[0x2d2a360] main access debug: no access module matching "http" could be loaded
[0x2d2a360] main access debug: waitpipe: object killed
[0x7f9a245b9520] main input error: open of `http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3' failed: (null)                                                                      
[0x7f9a245b9520] main input error: Your input can't be opened
[0x7f9a245b9520] main input error: VLC is unable to open the MRL 'http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3'. Check the log for details.                                   
[0x7f9a245b9520] main input debug: thread ended
amarok: BEGIN: void EngineController::slotFinished() 
amarok:   BEGIN: void ScrobblerAdapter::stopped(qint64, qint64) 
amarok:     BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:       [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:     END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:   END__: void ScrobblerAdapter::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void TimecodeObserver::stopped(qint64, qint64) 
amarok:   END__: void TimecodeObserver::stopped(qint64, qint64) [Took: 0s] 
amarok:   BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:   END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.037s] 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
PHONON-VLC   BEGIN: virtual void Phonon::VLC::MediaObject::stop() 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "PlayingState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC     BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC       "StoppedState" --> "StoppedState" 
PHONON-VLC     END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
PHONON-VLC   END__: virtual void Phonon::VLC::MediaObject::stop() [Took: 0.001s] 
PHONON-VLC   virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) 
PHONON-VLC   [ERROR__] virtual void Phonon::VLC::MediaObject::setSource(const Phonon::MediaSource&) MediaSource is empty. 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:   BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:     [EngineController] Empty MediaSource (engine stop) 
amarok:   END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
amarok: END__: void EngineController::slotFinished() [Took: 0.058s] 
PHONON-VLC BEGIN: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) 
PHONON-VLC   "StoppedState" --> "ErrorState" 
PHONON-VLC END__: void Phonon::VLC::MediaObject::stateChangedInternal(Phonon::State) [Took: 0s] 
State changed from 2 to 1 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0s] 
State changed from 1 to 5 -> sending to zeitgeist. 
amarok: BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok:   [WARNING] [EngineController] Phonon failed to play this URL. Error:  "" 
amarok:   [WARNING] [EngineController] Forcing phonon engine reinitialization. 
amarok:   BEGIN: void EngineController::initializePhonon() 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
"PulseSupport(2): Clearing stream cache for stream {88f4217d-6e18-4645-bce1-59429ebfea22}" 
"PulseSupport(2): Initialising streamindex {04f83c95-9701-4e84-bd4d-14893a26e56b}" 
"PulseSupport(2): Setting role to music for streamindex {04f83c95-9701-4e84-bd4d-14893a26e56b}" 
emitting a state change because the backend object has been replaced 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
PHONON-VLC     Backend connected Phonon::VLC::MediaObject to Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::AudioOutput 
PHONON-VLC     Object: Phonon::VLC::MediaObject 
void Phonon::FrontendInterfacePrivate::_backendObjectChanged() 
PHONON-VLC     createObject() : VolumeFaderEffect not implemented 
amarok:     [EngineController] Tick Interval (actual):  100 
amarok:   END__: void EngineController::initializePhonon() [Took: 0.004s] 
amarok:   BEGIN: void EngineController::slotAboutToFinish() 
amarok:     [EngineController] Track finished completely, updating statistics 
amarok:     BEGIN: void Playlist::Actions::requestNextTrack() 
amarok:       [Playlist::Actions] so far so good! 
amarok:       [Playlist::Actions] nothing more to play... 
amarok:       BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:         BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:         END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:       END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:       BEGIN: virtual void Playlist::Model::setAllUnplayed() 
amarok:         BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:         END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:       END__: virtual void Playlist::Model::setAllUnplayed() [Took: 0s] 
amarok:     END__: void Playlist::Actions::requestNextTrack() [Took: 0.001s] 
amarok:   END__: void EngineController::slotAboutToFinish() [Took: 0.001s] 
amarok: END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.006s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.006s] 
amarok: BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:   BEGIN: void DirWatchJob::setPaused(bool) 
amarok:   END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok: END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.007s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.002s] 
amarok: BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:   BEGIN: void DirWatchJob::setPaused(bool) 
amarok:   END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok: END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.009s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.007s] 
amarok: BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:   BEGIN: void DirWatchJob::setPaused(bool) 
amarok:   END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok: END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 
amarok: BEGIN: void SqlRegistry::emptyCache() 
amarok:   [SqlRegistry] Cache unchanged 
amarok: END__: void SqlRegistry::emptyCache() [Took: 0.007s]

Comment 5 Valorie Zimmerman 2011-03-30 09:44:48 UTC

As a follow-up, with a freshly built phonon-gst, I get the popup error: Unknown Artist - Genealogy: Ancestors on the Move :: Amarok requires an additional plugin for this operation
The following plugin is required: 
text/html
Do you want to search for this now? 

Of course it wasn't found. The output:

** Message: PackageKit: Did not install codec: failed to find codec
QInotifyFileSystemWatcherEngine::addPaths: inotify_add_watch failed: No such file or directory
QFileSystemWatcher: failed to add paths: /home/valorie/.config/ibus/bus
Bus::open: Can not get ibus-daemon's address. 
IBusInputContext::createInputContext: no connection to ibus-daemon 
amarok:   BEGIN: void Playlist::PrettyListView::trackActivated(const QModelIndex&) 
amarok:     BEGIN: void Playlist::Actions::play(const QModelIndex&) 
amarok:       BEGIN: void Playlist::Actions::play(quint64, bool) 
amarok:         BEGIN: void EngineController::play(Meta::TrackPtr, uint) 
amarok:           BEGIN: void EngineController::stop(bool) 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 5  QUrl( "" )  "" 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Empty MediaSource (engine stop) 
amarok:             END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0s] 
"PGST(2): Begin source load  (MediaObject 0xbeb680)" 
amarok:           END__: void EngineController::stop(bool) [Took: 0.002s] 
amarok:           [EngineController] play: bounded is  QObject(0x0)  current "Genealogy: Ancestors on the Move" 
amarok:           [EngineController] Just a normal, boring track... :-P 
amarok:           BEGIN: void EngineController::playUrl(const KUrl&, uint) 
amarok:             [EngineController] URL:  "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" 
amarok:             [EngineController] offset:  0 
void Phonon::MediaObject::setCurrentSource(const Phonon::MediaSource&) 1  QUrl( "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3" )  "" 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:             BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:               [EngineController] Using gain of 0 with relative peak of 0 
amarok:               BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                 episode:  "Genealogy: Ancestors on the Move" 
amarok:               END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:               BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:               END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0.001s]                                                                                                       
amarok:               BEGIN: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) 
amarok:                 [lastfm] track type: "mp3" 
amarok:                 BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:                   [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:                 END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:                 [lastfm] nowPlaying:  "[unknown]"  -  "Digging Up Your Roots"  -  "Genealogy: Ancestors on the Move" 
HTTP POST:  QUrl( "http://post.audioscrobbler.com:80/np_1.2" )  "s=222bd53322b04f3a8eaca1c06a421830&a=%5Bunknown%5D&t=Genealogy%3A%20Ancestors%20on%20the%20Move&b=Digging%20Up%20Your%20Roots&l=0&n=0&m=" 
amarok:               END__: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void TimecodeObserver::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void TimecodeObserver::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:                 BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:                 END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:                 BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:                   BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:                   END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:                 END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:               END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void ProgressWidget::trackLengthChanged(qint64) 
amarok:                 new length:  -1 
amarok:                 slider enabled! 
amarok:                 BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:                   BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:                     episode:  "Genealogy: Ancestors on the Move" 
amarok:                   END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:                   BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:                   END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s]                                                                                                       
amarok:                   found  0  timecodes on this track 
amarok:                 END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:               END__: void ProgressWidget::trackLengthChanged(qint64) [Took: 0s] 
amarok:               BEGIN: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) 
amarok:               END__: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:               BEGIN: void OSDWidget::show(const QString&, const QImage&) 
amarok:               END__: void OSDWidget::show(const QString&, const QImage&) [Took: 0.002s] 
amarok:             END__: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) [Took: 0.014s]                                                                                                          
"PGST(2): Begin source load  (MediaObject 0xbeb680)" 
amarok:             [EngineController] track pos after play:  0 
amarok:           END__: void EngineController::playUrl(const KUrl&, uint) [Took: 0.02s] 
amarok:         END__: void EngineController::play(Meta::TrackPtr, uint) [Took: 0.024s] 
amarok:       END__: void Playlist::Actions::play(quint64, bool) [Took: 0.025s] 
amarok:     END__: void Playlist::Actions::play(const QModelIndex&) [Took: 0.025s] 
amarok:   END__: void Playlist::PrettyListView::trackActivated(const QModelIndex&) [Took: 0.026s] 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): gstreamer: pipeline state set to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to paused  (MediaObject 0xbeb680)" 
"PGST(3): State changed from paused to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to ready  (MediaObject 0xbeb680)" 
"PGST(3): gstreamer: pipeline state set to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): gstreamer: pipeline state set to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to paused  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to paused  (MediaObject 0xbeb680)" 
"PGST(3): GST_MESSAGE_DURATION  (MediaObject 0xbeb680)" 
"PGST(1): Error: gstsouphttpsrc.c(1099): gst_soup_http_src_parse_status (): /GstPipeline:pipeline1/GstSoupHTTPSrc:souphttpsrc1:
404 Not Found Message: "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3": Not Found (Could not open resource for reading.) Code:5" 
"PGST(1): Phonon error: "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3": Not Found (code 2)" 
"PGST(2): phonon state changed : Error  (MediaObject 0xbeb680)" 
"PGST(1): "http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3": Not Found  (MediaObject 0xbeb680)" 
"PGST(1): Error: gstdecodebin2.c(1518): analyze_new_pad (): /GstPipeline:pipeline1/GstDecodeBin2:decodebin21:
No decoder to handle media type 'text/html' Message: A text/html decoder plugin is required to play this stream, but not installed. (There is no codec present that can handle the stream's type.) Code:6" 
"PGST(2): phonon state request: Loading  (MediaObject 0xbeb680)" 
"PGST(2): phonon state changed: Loading  (MediaObject 0xbeb680)" 
"PGST(3): State changed from paused to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from paused to ready  (MediaObject 0xbeb680)" 
State changed from 0 to 5 -> sending to zeitgeist. 
backend MediaObject reached ErrorState, trying Platform::createMediaStream now 
void Phonon::MediaObjectPrivate::_k_currentSourceChanged(const Phonon::MediaSource&) 
amarok:   BEGIN: void EngineController::slotNewTrackPlaying(const Phonon::MediaSource&) 
amarok:     [EngineController] Using gain of 0 with relative peak of 0 
amarok:     BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:       episode:  "Genealogy: Ancestors on the Move" 
amarok:     END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:     BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:     END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s] 
amarok:     BEGIN: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) 
amarok:       [lastfm] track type: "mp3" 
amarok:       BEGIN: void ScrobblerAdapter::checkScrobble() 
amarok:         [lastfm] total played 0 duration 0 isNull false submit? true 
amarok:       END__: void ScrobblerAdapter::checkScrobble() [Took: 0s] 
amarok:       [lastfm] nowPlaying:  "[unknown]"  -  "Digging Up Your Roots"  -  "Genealogy: Ancestors on the Move" 
amarok:     END__: void ScrobblerAdapter::trackPlaying(Meta::TrackPtr) [Took: 0.001s] 
amarok:     BEGIN: void TimecodeObserver::trackPlaying(Meta::TrackPtr) 
amarok:     END__: void TimecodeObserver::trackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:     BEGIN: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) 
amarok:       [WARNING] [Playlist::Actions] engineNewTrackPlaying: "Genealogy: Ancestors on the Move" does not match what the playlist controller thought it should be 
amarok:     END__: void Playlist::Actions::slotTrackPlaying(Meta::TrackPtr) [Took: 0s] 
amarok:     BEGIN: void ProgressWidget::trackLengthChanged(qint64) 
amarok:       new length:  -1 
amarok:       slider enabled! 
amarok:       BEGIN: void ProgressWidget::redrawBookmarks(const QString*) 
amarok:         BEGIN: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) 
amarok:           episode:  "Genealogy: Ancestors on the Move" 
amarok:         END__: TimecodeLoadCapabilityPodcastImpl::TimecodeLoadCapabilityPodcastImpl(Podcasts::PodcastEpisode*) [Took: 0s] 
amarok:         BEGIN: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() 
amarok:         END__: virtual BookmarkList TimecodeLoadCapabilityPodcastImpl::loadTimecodes() [Took: 0s] 
amarok:         found  0  timecodes on this track 
amarok:       END__: void ProgressWidget::redrawBookmarks(const QString*) [Took: 0s] 
amarok:     END__: void ProgressWidget::trackLengthChanged(qint64) [Took: 0s] 
amarok:     BEGIN: void Dynamic::EchoNestBias::trackPlaying(Meta::TrackPtr) 
amarok:     END__: void Dynamic::EchoNestBias::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.017s] 
PHONON-GST   BEGIN: Phonon::Gstreamer::StreamReader::StreamReader(const Phonon::MediaSource&, Phonon::Gstreamer::MediaObject*) 
PHONON-GST   END__: Phonon::Gstreamer::StreamReader::StreamReader(const Phonon::MediaSource&, Phonon::Gstreamer::MediaObject*) [Took: 0s] 
PHONON-GST   BEGIN: void Phonon::Gstreamer::StreamReader::start() 
PHONON-GST   END__: void Phonon::Gstreamer::StreamReader::start() [Took: 0s] 
PHONON-GST   BEGIN: void Phonon::Gstreamer::StreamReader::stop() 
PHONON-GST   END__: void Phonon::Gstreamer::StreamReader::stop() [Took: 0s] 
PHONON-GST   BEGIN: void Phonon::Gstreamer::StreamReader::start() 
PHONON-GST   END__: void Phonon::Gstreamer::StreamReader::start() [Took: 0s] 
PHONON-GST   BEGIN: GstFlowReturn Phonon::Gstreamer::StreamReader::read(quint64, int, char*) 
"PGST(2): Begin source load  (MediaObject 0xbeb680)" 
State changed from 5 to 0 -> sending to zeitgeist. 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to null  (MediaObject 0xbeb680)" 
** Message: PackageKit: xid = 33554525
** Message: PackageKit: Codec nice name: text/html
** Message: PackageKit: structure: gstreamer0.10(codec-text/html)()(64bit)
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from null to ready  (MediaObject 0xbeb680)" 
"PGST(3): gstreamer: pipeline state set to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to paused  (MediaObject 0xbeb680)" 
"PGST(3): State changed from ready to paused  (MediaObject 0xbeb680)" 
"OK" 
PHONON-GST     BEGIN: virtual void Phonon::Gstreamer::StreamReader::writeData(const QByteArray&) 
PHONON-GST     END__: virtual void Phonon::Gstreamer::StreamReader::writeData(const QByteArray&) [Took: 0s] 
PHONON-GST     BEGIN: virtual void Phonon::Gstreamer::StreamReader::endOfData() 
PHONON-GST     END__: virtual void Phonon::Gstreamer::StreamReader::endOfData() [Took: 0s] 
PHONON-GST   END__: GstFlowReturn Phonon::Gstreamer::StreamReader::read(quint64, int, char*) [Took: 0.47s] 
PHONON-GST   BEGIN: virtual void Phonon::Gstreamer::StreamReader::endOfData() 
PHONON-GST   END__: virtual void Phonon::Gstreamer::StreamReader::endOfData() [Took: 0s] 
"PGST(1): Error: gsttypefindelement.c(561): gst_type_find_element_handle_event (): /GstPipeline:pipeline1/GstDecodeBin2:decodebin21/GstTypeFindElement:typefind:
Can't typefind empty stream Message: Stream contains no data. (Could not determine type of stream.) Code:4" 
"PGST(1): Phonon error: Stream contains no data. (code 2)" 
PHONON-GST   BEGIN: void Phonon::Gstreamer::StreamReader::unlock() 
PHONON-GST   END__: void Phonon::Gstreamer::StreamReader::unlock() [Took: 0s] 
PHONON-GST   BEGIN: void Phonon::Gstreamer::StreamReader::stop() 
PHONON-GST   END__: void Phonon::Gstreamer::StreamReader::stop() [Took: 0s] 
"PGST(2): phonon state changed : Error  (MediaObject 0xbeb680)" 
"PGST(1): Stream contains no data.  (MediaObject 0xbeb680)" 
"PGST(3): State changed from paused to ready  (MediaObject 0xbeb680)" 
"PGST(3): State changed from paused to ready  (MediaObject 0xbeb680)" 
State changed from 0 to 5 -> sending to zeitgeist. 
amarok:   BEGIN: void EngineController::slotStateChanged(Phonon::State, Phonon::State) 
amarok:     [WARNING] [EngineController] Phonon failed to play this URL. Error:  "" 
amarok:     [WARNING] [EngineController] Forcing phonon engine reinitialization. 
amarok:     BEGIN: void EngineController::initializePhonon() 
PHONON-GST       BEGIN: virtual Phonon::Gstreamer::StreamReader::~StreamReader() 
PHONON-GST       END__: virtual Phonon::Gstreamer::StreamReader::~StreamReader() [Took: 0s] 
"PulseSupport(2): Clearing stream cache for stream {0eff99a3-f47c-4910-afff-72a17b9294d0}" 
"PGST(2): AudioOutput using pulsesink" 
"PulseSupport(2): Initialising streamindex {44478cf9-7d50-4a21-869a-a5600fec3054}" 
"PulseSupport(2): Setting role to music for streamindex {44478cf9-7d50-4a21-869a-a5600fec3054}" 
"PGST(2): Backend connected Phonon::Gstreamer::MediaObject to Phonon::Gstreamer::AudioOutput" 
void Phonon::FrontendInterfacePrivate::_backendObjectChanged() 
"PGST(2): Backend connected Phonon::Gstreamer::MediaObject to Phonon::Gstreamer::VolumeFaderEffect" 
"PGST(2): Backend connected Phonon::Gstreamer::VolumeFaderEffect to Phonon::Gstreamer::AudioOutput" 
amarok:       [EngineController] Tick Interval (actual):  100 
amarok:     END__: void EngineController::initializePhonon() [Took: 0.053s] 
amarok:     BEGIN: void EngineController::slotAboutToFinish() 
amarok:       [EngineController] Track finished completely, updating statistics 
amarok:       BEGIN: void Playlist::Actions::requestNextTrack() 
amarok:         [Playlist::Actions] so far so good! 
amarok:         [Playlist::Actions] nothing more to play... 
amarok:         BEGIN: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() 
amarok:           BEGIN: void Playlist::PrettyListView::scrollToActiveTrack() 
amarok:           END__: void Playlist::PrettyListView::scrollToActiveTrack() [Took: 0s] 
amarok:         END__: void Playlist::PrettyListView::slotPlaylistActiveTrackChanged() [Took: 0s] 
amarok:         BEGIN: virtual void Playlist::Model::setAllUnplayed() 
amarok:           BEGIN: void StatusBar::updateTotalPlaylistLength() 
amarok:           END__: void StatusBar::updateTotalPlaylistLength() [Took: 0s] 
amarok:         END__: virtual void Playlist::Model::setAllUnplayed() [Took: 0s] 
amarok:       END__: void Playlist::Actions::requestNextTrack() [Took: 0s] 
amarok:     END__: void EngineController::slotAboutToFinish() [Took: 0.001s] 
amarok:   END__: void EngineController::slotStateChanged(Phonon::State, Phonon::State) [Took: 0.055s] 
"PGST(3): State changed from null to ready  (MediaObject 0x7f9b70130ab0)" 
"PGST(3): State changed from null to ready  (MediaObject 0x7f9b70130ab0)" 
"PGST(3): gstreamer: pipeline state set to ready  (MediaObject 0x7f9b70130ab0)" 
** Message: PackageKit: Did not install codec: did not agree to search
amarok:   BEGIN: void ScanManager::checkForDirectoryChanges() 
amarok:     BEGIN: void DirWatchJob::setPaused(bool) 
amarok:     END__: void DirWatchJob::setPaused(bool) [Took: 0s] 
amarok:   END__: void ScanManager::checkForDirectoryChanges() [Took: 0s] 

Again, no podcast plays. Downloaded podcasts play, and radio streams play in phonon-gst. Newest phonon-VLC reports 

[0x3b6ff60] main audio output warning: audio drift is too big (1858032), dropping buffer
[0x3b6ff60] main audio output warning: audio drift is too big (1831909), dropping buffer
 
Until stopped by Control-C.

Comment 6 Ben Cooksley 2011-03-30 09:50:52 UTC

The lack of playback of the podcast is probably because the Podcast referenced no longer exists on the host:

ben@bianca:~> wget http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3
--2011-03-30 20:56:08--  http://downloads.bbc.co.uk/podcasts/radioscotland/genealogy/genealogy_20110221-1200a.mp3
Resolving downloads.bbc.co.uk... 212.58.224.150
Connecting to downloads.bbc.co.uk|212.58.224.150|:80... connected.
HTTP request sent, awaiting response... 404 Not Found
2011-03-30 20:56:10 ERROR 404: Not Found.

Comment 7 Valorie Zimmerman 2011-03-30 10:25:18 UTC

::blush::!

Once I found a recent podcast that does still exist, phonon-gst plays it. Phonon-vlc does not, however. Same error.

Comment 8 Harald Sitter 2011-03-30 12:36:02 UTC

I doubt it got the same log as the present pvlc log states http error 404 (namely the file does not exist). Please get a new log.

Comment 9 Myriam Schweingruber 2011-04-01 20:38:33 UTC

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

Comment 10 Valorie Zimmerman 2011-04-02 11:34:29 UTC

Gah, I commented on https://bugs.kde.org/show_bug.cgi?id=269921 instead, i guess. 

Many of these in pvlc:

[0x40a4720] main audio output warning: audio drift is too big (120029), dropping buffer

With an occasional:

[0x3d31a80] mpgatofixed32 audio filter debug: libmad error: bad main_data_begin pointer
[0x40a4720] main audio output warning: output date isn't PTS date, requesting resampling (451617)

Comment 11 Harald Sitter 2011-04-02 14:55:49 UTC

Now I am confused...

You get those messages and *then* amarok crashes or what?

Comment 12 Harald Sitter 2011-04-02 14:56:14 UTC

At any rate those are upstream problems.

Comment 13 Myriam Schweingruber 2011-04-27 21:33:39 UTC

Reassigning to the new bugzilla product for better bug tracing of the various
backends. Sorry for the noise.

Comment 14 Harald Sitter 2011-04-28 10:06:47 UTC

Closing as they are upstream issues.

Comment 15 Myriam Schweingruber 2011-06-09 10:51:59 UTC

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

Comment 16 Myriam Schweingruber 2011-12-27 19:31:17 UTC

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

Comment 17 Russ Fineman 2011-12-28 18:50:27 UTC

Comment on bug 285798 #16 above. "You probably have set the playback to restart automatically on Amarok start and
that seems to cause some problems with the vlc backend. Try disabling that
option.
The black screen though is unrelated to this crash."

I check Amarok setting and the one mentioned is Not set. The crash only occured the first time I tried restarting the system. Appears OK now. Amarok is working with VLC.