Bugzilla – Bug 18041
Server crash when playing podcast
Last modified: 2013-12-04 05:34:35 UTC
Steps to reproduce: 1) In Settings > Podcasts, add the following feed: http://extra.expressen.se/podcast/envargsokersinpod/podcast.xml 2) Back on home screen, open Podcasts under Extras, select the newly subscribed podcast "En varg söker sin pod" and select any chapter. 3) Observe that the podcast does not play. I have tried with other podcast player where it works fine.
In fact, squeezeserver seems to crash and restart when playing this podcast.
In the logs: [13-06-14 14:45:46.3092] Slim::Plugin::Podcast::Plugin::songChangeCallback (117) Setting up timer to track podcast progress... 2013-06-14 14:45:46 Logitech Media Server died. Restarting. [13-06-14 14:45:49.5264] main::init (355) Starting Logitech Media Server (v7.8.0, 1371193145, Fri Jun 14 02:00:47 PDT 2013) perl 5.008008 (btw, just upgraded from 7.7.2 to 7.8.0 nightly to see if the problem already was fixed. i.e. same problem in 7.7.2).
Does this crash happen with any episode of this podcast? Do you see the same issue when running LMS on your PC? This seems to be working fine for me. Only tried the first episode, though.
I cannot reproduce this after installing Squeezeserver (the package logitechmediaserver_7.7.2_all.deb) on my Ubuntu PC. Another way of reproducing the bug (on my ReadyNAS): 1) Copy the mp3-file for the first episode of the podcast (will attach to bug) to music repository on the ReadyNAS. 2) Rescan music database 3) (if it didn't crash during scanning) 4) Go to home screen, select "music folder" 5) Observe that you will get an error page from the browser since squeezeserver has crashed and is rebooting. So it seems to be some problem with this particular mp3-file that triggers a bug when opening/reading it.
Downloaded this file: http://extra.expressen.se/podcast/Envargsokersinpod/en_varg_asiktsproletariatet.mp3 Crashes squeezeserver on ReadyNAS when put in music repository.
turned on some more logging: [13-06-17 15:32:53.5580] Slim::Utils::Misc::stripRel (620) Original: /media/Music/radio/en_varg_asiktsproletariatet.mp3 [13-06-17 15:32:53.5588] Slim::Utils::Misc::stripRel (626) Stripped: /media/Music/radio/en_varg_asiktsproletariatet.mp3 [13-06-17 15:32:53.5603] Slim::Utils::Misc::fixPath (603) Fixed: en_varg_asiktsproletariatet.mp3 to file:///media/Music/radio/en_varg_asiktsproletari atet.mp3 [13-06-17 15:32:53.5611] Slim::Utils::Misc::fixPath (605) Base: /media/Music/radio [13-06-17 15:32:53.5650] Slim::Schema::Debug::query_start (23) SELECT me.id, me.urlmd5, me.url, me.content_type, me.title, me.titlesort, me.titlesear ch, me.album, me.primary_artist, me.tracknum, me.timestamp, me.added_time, me.updated_time, me.filesize, me.disc, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.cover_cached, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignmen t, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.dlna_profile, me.musicbrainz_id, me.lossless, me.lyrics, me.replay_gain, me.re play_peak, me.extid, me.virtual, me.coverid FROM tracks me WHERE ( url = ? ): 'file:///media/Music/radio/en_varg_asiktsproletariatet.mp3' [13-06-17 15:32:53.5697] Slim::Schema::Debug::query_start (23) SELECT me.id, me.urlmd5, me.url, me.content_type, me.title, me.titlesort, me.titlesear ch, me.album, me.primary_artist, me.tracknum, me.timestamp, me.added_time, me.updated_time, me.filesize, me.disc, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.cover_cached, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignmen t, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.dlna_profile, me.musicbrainz_id, me.lossless, me.lyrics, me.replay_gain, me.re play_peak, me.extid, me.virtual, me.coverid FROM tracks me WHERE ( url = ? ): 'file:///media/Music/radio/en_varg_asiktsproletariatet.mp3' [13-06-17 15:32:53.5712] Slim::Schema::_newTrack (1504) New Track: [file:///media/Music/radio/en_varg_asiktsproletariatet.mp3] [13-06-17 15:32:53.5720] Slim::Schema::_newTrack (1514) readTags is 1 [13-06-17 15:32:53.5730] Slim::Utils::Misc::pathFromFileURL (250) Got /media/Music/radio/en_varg_asiktsproletariatet.mp3 from file url file:///media/ Music/radio/en_varg_asiktsproletariatet.mp3 [13-06-17 15:32:53.5741] Slim::Utils::Misc::pathFromFileURL (267) Extracted: /media/Music/radio/en_varg_asiktsproletariatet.mp3 from file:///media/Mu sic/radio/en_varg_asiktsproletariatet.mp3 [13-06-17 15:32:53.5751] Slim::Music::Info::typeFromPath (1540) mp3 file type for /media/Music/radio/en_varg_asiktsproletariatet.mp3 2013-06-17 15:32:53 Logitech Media Server died. Restarting. [13-06-17 15:32:56.7678] main::init (355) Starting Logitech Media Server (v7.8.0, 1371193145, Fri Jun 14 02:00:47 PDT 2013) perl 5.008008 [13-06-17 15:32:57.7194] Slim::Utils::Misc::msg (1304) Warning: [15:32:57.7186] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/share/perl5/Slim/Schema.pm line 1888
Could this be a duplicate of bug 17849?
Interesting, yes, that might be the case. I have a library of 40k audio files and haven't encountered this bug until now when I subscribed to this podcast. Running the test case in comment#12 (https://bugs-archive.lyrion.org/show_bug.cgi?id=17849#c12) does indeed work on my Ubuntu PC, but crashes on my ReadyNAS using that particular mp3-file (linked to above).
You don't have access to a ReadyNAS for debugging? If so, can you reproduce? Is it possible to generate a full stack trace? Can you provide a Scan.so with debug symbols enabled? This particular mp3-file seems to contain a lot of XML-metadata in the ID3-tag - could that be a problem maybe?
Any update on this bug? This podcast still crashes the server (every episode) on ReadyNAS. Can I help debugging the issue in any way?
*** This bug has been marked as a duplicate of bug 17849 ***