Bug 17674 - Segmentation fault after separate rescan of music folder but only if you have images or video enabled
: Segmentation fault after separate rescan of music folder but only if you have...
Status: RESOLVED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: Scanner
: 7.7.0
: PC RedHat Linux
: P1 normal with 2 votes (vote)
: 7.7.1
Assigned To: Andy Grundman
: libmediascan
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2011-10-18 09:17 UTC by Mikael Nyberg
Modified: 2011-11-20 15:37 UTC (History)
2 users (show)

See Also:
Category: ---


Attachments
scanning ! new album (38.45 KB, text/plain)
2011-10-18 10:43 UTC, Mikael Nyberg
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Mikael Nyberg 2011-10-18 09:17:17 UTC
After resolving why I could not rescan a specific folder (17639,17657) .

Now when I can do that, LMS segfaults after the scan of a music folder.

I have  Music, Pictures, and Video folders and a playlist folder .

Rescanning video pictures and playlist works fine.

But if I initiate a rescan of a music folder LMS segfaults after the scan is done (maybe the cleanup is missing ) .
If I have no new music LMS finishes the scan then segfaults,
If I have new music LMS scans and find the new music then segfaults.
I can see the new music in the "New Music" menu when I restart.

However if I run LMS from console with --noimage --novideo

Then it is possible to initiate separate scans of one of my 2 music folders.
And it works fine no problems .

If I'm not clear enough the mere presence of image and video makes a separate scan of music segfault.

Logitech Media Server Version: 7.7.0 - r33609 @ Sat Oct 15 04:02:07 MDT 2011
Hostname: hal.home.lan
IP: 192.168.1.5
HTTP Port: 9000
OS: Red Hat - EN - utf8
Platform: i686-linux
Perl Version: 5.8.8 - i686-linux-thread-multi
Database Version: DBD::SQLite 1.34_01 (sqlite 3.7.7.1)
Comment 1 Mikael Nyberg 2011-10-18 09:18:45 UTC
Console output .

[root@hal /]# /usr/libexec/squeezeboxserver
[11-10-17 23:19:16.4560] main::init (354) Starting Logitech Media Server (v7.7.0, r33609, Sat Oct 15 04:02:07 MDT 2011) perl 5.008008
[11-10-17 23:19:16.4714] main::changeEffectiveUserAndGroup (966) Warning: Logitech Media Server must not be run as root!  Trying user squeezeboxserver instead.
[11-10-17 23:19:17.1288] Slim::Music::Import::addImporter (456) Adding Slim::Media::MediaFolderScan Scan
[11-10-17 23:19:17.1310] Slim::Music::Import::countImporters (525) Found importer: Slim::Media::MediaFolderScan
[11-10-17 23:19:17.5239] Slim::Music::Import::addImporter (456) Adding Slim::Music::PlaylistFolderScan Scan
[11-10-17 23:19:17.5256] Slim::Music::Import::countImporters (525) Found importer: Slim::Media::MediaFolderScan
[11-10-17 23:19:17.5278] Slim::Music::Import::countImporters (525) Found importer: Slim::Media::MediaFolderScan
[11-10-17 23:19:17.5293] Slim::Music::Import::countImporters (525) Found importer: Slim::Music::PlaylistFolderScan
/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotify/Bin/i386-linux/spotifyd: error while loading shared libraries: requires glibc 2.5 or later dynamic linker
/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotify/Bin/i386-linux/spotifydnoflac 2.1.0 logging to /var/log/squeezeboxserver/spotifyd.log
[11-10-17 23:20:26.7998] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-17 23:20:27.1419] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-17 23:20:37.0077] Slim::Music::Import::initScanQueue (683) initialize scan queue
[11-10-17 23:20:37.0097] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sda1/music1 to queue
[11-10-17 23:20:37.0123] Slim::Music::Import::nextScanTask (697) triggering next scan: rescan|full|file:///media/sda1/music1
[11-10-17 23:20:37.0166] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sda1/music1
[11-10-17 23:20:37.0298] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sda1/music1
[11-10-17 23:20:37.9608] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-17 23:21:16.8720] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 14986 files/dirs in 38.92 sec
[11-10-17 23:21:22.5556] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-10-17 23:21:22.5574] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (0)
[11-10-17 23:21:22.5591] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-10-17 23:21:22.5733] Slim::Utils::Scanner::LMS::rescan (91) Rescanning 
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 41 height 41
ms_add_thumbnail_spec width 40 height 40
ms_add_thumbnail_spec width 100 height 100
Thread 0xb5ed5ba0 started
progress_update (null)
new equeue_entry @ 0xc8aecc0 (type 2, data @ 0xe43a890)
thread_signal -> 44
Finished scanning
new equeue_entry @ 0xf007378 (type 4, data @ (nil))
thread_signal -> 44
[11-10-17 23:21:22.8431] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
thread_signal_read <- 41 waiting...
thread_signal_read <- 41 OK
Got thread event, type 2 @ 0xe43a890
Segmentation fault
Comment 2 Mikael Nyberg 2011-10-18 09:20:15 UTC
Another one:

[11-10-17 23:57:46.0584] Slim::Music::Import::initScanQueue (683) initialize scan queue
[11-10-17 23:57:46.0610] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sda1/music1 to queue
[11-10-17 23:57:46.0642] Slim::Music::Import::nextScanTask (697) triggering next scan: rescan|full|file:///media/sda1/music1
[11-10-17 23:57:46.0691] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sda1/music1
[11-10-17 23:57:46.0797] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sda1/music1
[11-10-17 23:57:46.8663] Slim::Music::Import::nextScanTask (702) remaining scans in queue)
[11-10-17 23:58:28.9416] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 14986 files/dirs in 42.22 sec
[11-10-17 23:58:31.5226] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-10-17 23:58:31.5254] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (0)
[11-10-17 23:58:31.5272] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-10-17 23:58:31.5413] Slim::Utils::Scanner::LMS::rescan (91) Rescanning
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 41 height 41
ms_add_thumbnail_spec width 40 height 40
ms_add_thumbnail_spec width 100 height 100
Thread 0xb6337ba0 started
[11-10-17 23:58:31.5586] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
[11-10-17 23:58:31.5652] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
progress_update (null)
new equeue_entry @ 0xdb1f0b0 (type 2, data @ 0xf366060)
thread_signal -> 23
Finished scanning
new equeue_entry @ 0xc62ecb8 (type 4, data @ (nil))
thread_signal -> 23
[11-10-17 23:58:31.7187] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
thread_signal_read <- 22 waiting...
thread_signal_read <- 22 OK
Got thread event, type 2 @ 0xf366060
Segmentation fault
Comment 3 Mikael Nyberg 2011-10-18 09:22:33 UTC
This :

type 4, data @ (nil)

Is it ok ?
Comment 4 Andy Grundman 2011-10-18 09:44:53 UTC
Can you make this change which will produce a lot more log output?

--- Slim/Utils/Scanner/LMS.pm	(revision 79826)
+++ Slim/Utils/Scanner/LMS.pm	(local)
@@ -203,7 +203,7 @@
 	
 	# Begin scan
 	$s = Media::Scan->new( $paths, {
-		loglevel => $log->is_debug ? MS_LOG_DEBUG : MS_LOG_ERR, # Set to MS_LOG_MEMORY for very verbose logging
+		loglevel => $log->is_debug ? MS_LOG_MEMORY : MS_LOG_ERR, # Set to MS_LOG_MEMORY for very verbose logging
 		async => $args->{no_async} ? 0 : 1,
 		flags => $flags,
 		cachedir => $prefs->get('librarycachedir'),
Comment 5 Mikael Nyberg 2011-10-18 10:15:27 UTC
(In reply to comment #4)
> Can you make this change which will produce a lot more log output?

Hopefully i've got it ?

This is the output:

[11-10-18 19:11:11.5000] Slim::Music::Import::initScanQueue (683) initialize scan queue
[11-10-18 19:11:11.5018] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sda1/music1 to queue
[11-10-18 19:11:11.5050] Slim::Music::Import::nextScanTask (697) triggering next scan: rescan|full|file:///media/sda1/music1
[11-10-18 19:11:11.5093] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sda1/music1
[11-10-18 19:11:11.5238] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sda1/music1
[11-10-18 19:11:12.4798] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-18 19:11:53.1999] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 14986 files/dirs in 40.73 sec
[11-10-18 19:11:58.9300] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-10-18 19:11:58.9318] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (0)
[11-10-18 19:11:58.9335] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-10-18 19:11:58.9479] Slim::Utils::Scanner::LMS::rescan (91) Rescanning 
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 160 height 160
ms_add_thumbnail_spec width 41 height 41
ms_add_thumbnail_spec width 40 height 40
ms_add_thumbnail_spec width 100 height 100
new thread_data @ 0xfda4f90
new MediaScanThread @ 0xf7e84f0
new equeue @ 0xfd103b0
Thread 0xb4ceeba0 started
progress_update (null)
copy MediaScanProgress @ 0xfd31168 -> 0x104900b8
new equeue_entry @ 0x10619158 (type 2, data @ 0x104900b8)
thread_signal -> 39
Finished scanning
new equeue_entry @ 0xfe9ee60 (type 4, data @ (nil))
thread_signal -> 39
destroy thread_data @ 0xfda4f90
[11-10-18 19:12:01.9031] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
thread_signal_read <- 38 waiting...
thread_signal_read <- 38 OK
destroy equeue_entry @ 0x10619158
Got thread event, type 2 @ 0x104900b8
Segmentation fault
[root@hal ~]#
Comment 6 Mikael Nyberg 2011-10-18 10:43:35 UTC
Created attachment 7526 [details]
scanning ! new album

Another run with one new album .

Can't help to notice that it is doing things a lot of already scanned files ?

I made a text file it was a lot.
Comment 7 Mikael Nyberg 2011-10-21 01:23:31 UTC
More info , 1/2 times the scan aborts when i rescan. probably because of the previus failed scan.
The interesting thing is that I don't get segfault if the scan aborts itself.

After this aborted scan I can scan again, and then we have the segfault.



[11-10-21 10:05:31.7366] Slim::Music::Import::initScanQueue (683) initialize scan queue
[11-10-21 10:05:31.7386] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sda1/music1 to queue
[11-10-21 10:05:31.7412] Slim::Music::Import::nextScanTask (697) triggering next scan: rescan|full|file:///media/sda1/music1
[11-10-21 10:05:31.7456] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sda1/music1
[11-10-21 10:05:31.7555] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sda1/music1
[11-10-21 10:05:32.6626] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-21 10:06:12.0692] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 14986 files/dirs in 39.42 sec
[11-10-21 10:06:17.9399] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-10-21 10:06:17.9419] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (0)
[11-10-21 10:06:17.9436] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-10-21 10:06:17.9454] Slim::Utils::Scanner::Local::hasAborted (541) Scan aborted
[11-10-21 10:06:20.9024] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-21 10:06:20.9060] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
[11-10-21 10:06:45.0317] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-21 10:06:45.3048] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-10-21 10:06:53.1032] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
Comment 8 Mikael Nyberg 2011-10-21 01:43:36 UTC
Just to be sure I made another experiment I removed all content in images and video directories, rescanned those so that LMS knew it was nothing there .

same result
Comment 9 Mikael Nyberg 2011-10-21 04:59:34 UTC
Another worrying result .

It is not enough to not have any image or video directories at all specified in the settings .

it still segfaults in the same way

You really must use the --noimage --novideo options to avoid the segfault.



new thread_data @ 0xebeb040
new MediaScanThread @ 0xb568f00
new equeue @ 0xecc00c8
Thread 0xb4d87ba0 started
[11-10-21 13:50:12.4553] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
progress_update (null)
copy MediaScanProgress @ 0xed770e0 -> 0xf5e7858
new equeue_entry @ 0xf5e7890 (type 2, data @ 0xf5e7858)
thread_signal -> 43
Finished scanning
new equeue_entry @ 0xf5e78a8 (type 4, data @ (nil))
thread_signal -> 43
destroy thread_data @ 0xebeb040
thread_signal_read <- 41 waiting...
thread_signal_read <- 41 OK
destroy equeue_entry @ 0xf5e7890
Got thread event, type 2 @ 0xf5e7858
Segmentation fault
Comment 10 Mikael Nyberg 2011-10-23 10:06:23 UTC
You can upgrade this bug further .

Now a LMS also segfaults on normal rescans too , not only those initiated for a separate folder.

new thread_data @ 0xdb51fc8
new MediaScanThread @ 0xe08fb30
new equeue @ 0xe89aae8
Thread 0xb6b7aba0 started
progress_update (null)
copy MediaScanProgress @ 0xe2727f8 -> 0xe1205f0
new equeue_entry @ 0xdf582c8 (type 2, data @ 0xe1205f0)
thread_signal -> 40
Finished scanning
new equeue_entry @ 0xe1f9970 (type 4, data @ (nil))
thread_signal -> 40
destroy thread_data @ 0xdb51fc8
thread_signal_read <- 39 waiting...
thread_signal_read <- 39 OK
destroy equeue_entry @ 0xdf582c8
Got thread event, type 2 @ 0xe1205f0
Segmentation fault
Comment 11 Mikael Nyberg 2011-10-23 10:50:26 UTC
(In reply to comment #10)
> You can upgrade this bug further .
> 
> Now a LMS also segfaults on normal rescans too , not only those initiated for a
> separate folder.

The change I made was to not have any picture or video folders at all specified
Comment 12 Erland Isaksson 2011-10-31 21:19:15 UTC
Just for information, I have exactly the same issue in my Ubuntu setup with perl 5.8.8, with and without third party plugins.

It works perfectly if I use --noimage --novideo when starting SBS but without these flags the scanner crashes with segmentation faults most of the time I do a new/changed rescan and I have more than one music folder configured.

With only one music folder configured it works, but with two it results in segmentation fault most of the time.

I've tried with different music folders so I don't think it's related to the specific music files.
Comment 13 Michael Herger 2011-11-04 02:34:33 UTC
I have seen this in the past too, but never was able to figure out why and when it would happen - your instructions are spot on! Thanks!
Comment 14 SVN Bot 2011-11-04 02:54:00 UTC
 == Auto-comment from SVN commit #33655 to the slim repo by mherger ==
 == http://svn.slimdevices.com/slim?view=revision&revision=33655 ==

Bug: 17674
Description: don't run media scanner if no media folder is defined.

Erland/Michael - this should fix the case you're seeing, where the scanner is crashing if you haven't defined any video/photo folder. 

Unfortunately it still crashes for me every now and then if I have one of those folders defined. It's most likely an issue in Media::Scan causing the crash.
Comment 15 Michael Herger 2011-11-04 04:31:45 UTC
Andy - could we have an issue with Media::Scan's housekeeping? External scans never fail. And I doubt I've ever seen a first internal scan crash. But it happens reliably if I rescan a few times in a row.
Comment 16 Mikael Nyberg 2011-11-04 07:20:56 UTC
The case of no image or video directory specified seems fixed . +1
it is nw working ok

I'll continue to enable a picture and a video directory again .

To repeat , my original case was that rescanning any of the music directories gave segfaults , when picture and video directories existed, (scanning of video or pictures was ok)

For some reason it later started to happen to "new and changed" scans to, not only those scans aimed at a specific directory.
Comment 17 Mikael Nyberg 2011-11-04 09:35:18 UTC
Well having an active picture folder no video yet .
(Erlands plugins still active ).

No segfaults however scan is weird it seems to find already scanned content ? I add 1 album and it finds 73 new tracks a nd resizes 11 images, or 142 images or something else ?

[11-11-04 17:26:12.3110] Slim::Music::Import::initScanQueue (685) initialize scan queue
[11-11-04 17:26:12.3130] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sdb1/music2 to queue
[11-11-04 17:26:12.3156] Slim::Music::Import::nextScanTask (698) triggering next scan: rescan|full|file:///media/sdb1/music2
[11-11-04 17:26:12.3195] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sdb1/music2
[11-11-04 17:26:12.3316] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sdb1/music2
[11-11-04 17:26:13.4549] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-11-04 17:27:10.6633] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 21125 files/dirs in 57.22 sec
[11-11-04 17:27:16.6727] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-11-04 17:27:16.6744] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (73)
[11-11-04 17:27:16.6789] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-11-04 17:27:18.1681] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Don%27t%20Worry.flac
[11-11-04 17:27:18.2671] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Escape%20Artist.flac
[11-11-04 17:27:18.3052] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Flying%20&%20Flocking.flac
[11-11-04 17:27:18.3390] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Forest.flac
[11-11-04 17:27:18.4022] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Hello%20Night.flac
[11-11-04 17:27:18.4406] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Lost.flac
[11-11-04 17:27:18.4753] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Optimist%20(live).flac
[11-11-04 17:27:18.5087] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Optimist.flac
[11-11-04 17:27:18.5703] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/Seven%20League%20Boots.flac
[11-11-04 17:27:18.6180] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/The%20Last%20Bird.flac
[11-11-04 17:27:18.6816] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Bandcamp/Zoe%20Keating%20-%20Into%20The%20Trees/The%20Path.flac
[11-11-04 17:27:18.7220] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%201.m4b
[11-11-04 17:27:18.7274] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%201.m4b: Unable to read tags from file
[11-11-04 17:27:18.7716] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%202.m4b
[11-11-04 17:27:18.7767] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%202.m4b: Unable to read tags from file
[11-11-04 17:27:20.4916] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Looking%20for%20love%20[iPod%20Video].mp4
[11-11-04 17:27:20.4968] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Looking%20for%20love%20[iPod%20Video].mp4: Unable to read tags from file
[11-11-04 17:27:20.5101] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Satellites%20[iPod%20Video].mp4
[11-11-04 17:27:20.5151] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Satellites%20[iPod%20Video].mp4: Unable to read tags from file
[11-11-04 17:27:20.6734] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/SR/Alexander%20Solsjenitsyn%20-%20En%20dag%20i%20Ivan%20Denisovitjs%20liv%20[Radioteatern].m4b
[11-11-04 17:27:20.6793] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/SR/Alexander%20Solsjenitsyn%20-%20En%20dag%20i%20Ivan%20Denisovitjs%20liv%20[Radioteatern].m4b: Unable to read tags from file
[11-11-04 17:27:22.2654] Slim::Utils::Scanner::Local::markDone (999) Finished scan type 2 for /media/sdb1/music2
[11-11-04 17:27:22.2673] Slim::Utils::Scanner::Local::markDone (1017) All rescan tasks finished (total changes: 73)
[11-11-04 17:27:22.8234] Slim::Music::Artwork::precacheAllArtwork (442) Starting precacheArtwork for 11 albums
[11-11-04 17:27:23.3108] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Into The Trees from /media/sdb1/music2/Bandcamp/Zoe Keating - Into The Trees/The Path.flac
[11-11-04 17:27:23.4389] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Golden Age of Christmas Music from /media/sdb1/music2/Lossles Music/The Golden Age of Christmas Music [FLAC]/The Golden Age of Christmas Music Disc 3/21 - Bobby Helms - Jingle Bell Rock.flac
[11-11-04 17:27:23.5451] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Chrome, Smoke & BBQ (Disc 1) from /media/sdb1/music2/Lossles Music/ZZ Top/ZZ TOP - 2003 - Chrome, Smoke & BBQ/ZZ Top - Chrome, Smoke & BBQ (Disc 1)/20 - Master Of Sparks.flac
[11-11-04 17:27:23.6448] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Lord Of The Rings: The Fellowship Of The Ring (The Complete Recordings) from /media/sda1/music1/CD/Howard Shore/Howard Shore (2005) The Lord Of The Rings The Fellowship Of The Ring (The Complete Recordings)/Howard Shore (2005) The Lord Of The Rings The Fellowship Of The Ring (The Complete Recordings) (disc 1)/Very Old Friends.flac
[11-11-04 17:27:23.7262] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Främmande Natt CD1 Sordin , Lagt kort ligger from /media/sdb1/music2/Lossy Music/John Holm Främmande Natt 4 CD/Främmande Natt CD1 Sordin Lagt Kort Ligger/Svarte kungen.mp3
[11-11-04 17:27:23.8321] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Complete Riverside Recordings from /media/sdb1/music2/Lossles Music/Bill Evans - The Complete Riverside Recordings/Bill Evans (1962) The Complete Riverside Recordings (Disc 9)/You Go To My Head.flac
[11-11-04 17:27:23.9278] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Natural Forces from /media/sda1/music1/CD/Lyle Lovett/Lyle Lovett (2009) Natural Forces/Whooping Crane.flac
[11-11-04 17:27:24.0061] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Ola Onabule from /media/sda1/music1/CD/The devoured man (2007) Ola Onabule/12 - Back Home.flac
[11-11-04 17:27:24.0916] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Texas Troubadour from /media/sda1/music1/CD/Townes Van Zandt (2001) Texas Troubadour/Townes Van Zandt (2001) Texas Troubadour (disc 1)/22 - Whe She's Acting This Way.flac
[11-11-04 17:27:24.1665] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Complete Live at the Plugged Nickel 1965 from /media/sdb1/music2/Lossles Music/Miles Davis - The Complete Live at the Plugged Nickel (1965)(1995 Remaster)/Miles Davis - The Complete Live at the Plugged Nickel _4/05 - The Theme.flac
[11-11-04 17:27:24.3431] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Mäster Cees memoarer from /media/sdb1/music2/Bamse/Cornelis Vreeswijk (1993) Mäster Cees memoarer/Cornelis Vreeswijk (1993) Mäster Cees memoarer - Vol.2/30 - Huvudlösen för aftonen.flac
[11-11-04 17:27:24.4434] Slim::Music::Artwork::__ANON__ (547) precacheArtwork finished in 1.61731696128845
[11-11-04 17:27:24.4452] Slim::Utils::Scanner::Local::__ANON__ (1036) Scanner made 73 changes, updating last rescan timestamp
[11-11-04 17:27:24.4567] Slim::Utils::Scanner::Local::__ANON__ (1044) Scan change count reached 135, optimizing database
[11-11-04 17:27:24.4587] Slim::Schema::optimizeDB (436) Start schema_optimize
[11-11-04 17:27:28.3805] Slim::Schema::optimizeDB (450) End schema_optimize
[11-11-04 17:27:28.4500] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-11-04 17:27:28.7150] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
Comment 18 Mikael Nyberg 2011-11-04 10:41:48 UTC
Ok tried 2 more album with none of Erlands plugins enabled .

It still wont crash ? I will add a video folder too  but already having pictures proves the piont.

But the original problem seems hard to reproduce ?
You said that you probably have not fixed all the problems ?

Note that with only LMS doing the scan (no third party plug ins )
it does tons of wierd stuff with already scanned content .

The only thing new here is the Elmore James album.

[11-11-04 18:36:58.2579] Slim::Music::Import::initScanQueue (685) initialize scan queue
[11-11-04 18:36:58.2598] Slim::Music::Import::queueScanTask (740) adding scan rescan|full|file:///media/sdb1/music2 to queue
[11-11-04 18:36:58.2623] Slim::Music::Import::nextScanTask (698) triggering next scan: rescan|full|file:///media/sdb1/music2
[11-11-04 18:36:58.2673] Slim::Utils::Scanner::Local::rescan (147) Rescanning /media/sdb1/music2
[11-11-04 18:36:58.2813] Slim::Utils::Scanner::Local::rescan (171) Discovering audio files in /media/sdb1/music2
[11-11-04 18:36:59.4125] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-11-04 18:37:55.0034] Slim::Utils::Scanner::Local::AIO::__ANON__ (80) AIO scanner found 21151 files/dirs in 55.60 sec
[11-11-04 18:38:00.9085] Slim::Utils::Scanner::Local::__ANON__ (254) Removing deleted audio files (0)
[11-11-04 18:38:00.9104] Slim::Utils::Scanner::Local::__ANON__ (332) Scanning new audio files (76)
[11-11-04 18:38:00.9149] Slim::Utils::Scanner::Local::__ANON__ (410) Rescanning changed audio files (0)
[11-11-04 18:38:02.5786] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%201.m4b
[11-11-04 18:38:02.6063] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%201.m4b: Unable to read tags from file
[11-11-04 18:38:02.6489] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%202.m4b
[11-11-04 18:38:02.6541] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Ljudb/Slavomir%20Rawicz%20-%20Flykten%20fra%CC%8An%20Stalins%20la%CC%88ger%20-%20Part%202.m4b: Unable to read tags from file
[11-11-04 18:38:02.6899] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Anna%20Lee.flac
[11-11-04 18:38:02.8387] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Baby%20Please%20Set%20A%20Date.flac
[11-11-04 18:38:02.8758] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Bobby%27s%20Rock%20(Instrumental).flac
[11-11-04 18:38:02.9681] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Dust%20My%20Broom.flac
[11-11-04 18:38:03.0457] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Fine%20Little%20Mama.flac
[11-11-04 18:38:03.0829] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Got%20To%20Move.flac
[11-11-04 18:38:03.1915] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Held%20My%20Baby%20Last%20Night.flac
[11-11-04 18:38:03.2773] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/I%20Can%27t%20Stop%20Lovin%27%20You.flac
[11-11-04 18:38:03.3860] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Look%20On%20Yonder%20Wall.flac
[11-11-04 18:38:03.5462] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Rollin%27%20And%20Tumblin%27.flac
[11-11-04 18:38:03.5817] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/So%20Unkind.flac
[11-11-04 18:38:03.6639] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Something%20Inside%20Of%20Me.flac
[11-11-04 18:38:03.8691] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/Stranger%20Blues.flac
[11-11-04 18:38:03.9357] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/Elmore%20James%20-%20Slip%20Sliding%20Away/The%20Sky%20Is%20Crying.flac
[11-11-04 18:38:05.6176] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Looking%20for%20love%20[iPod%20Video].mp4
[11-11-04 18:38:05.6228] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Looking%20for%20love%20[iPod%20Video].mp4: Unable to read tags from file
[11-11-04 18:38:05.6360] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Satellites%20[iPod%20Video].mp4
[11-11-04 18:38:05.6409] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/Lossles%20Music/September%20-%20In%20Orbit/Satellites%20[iPod%20Video].mp4: Unable to read tags from file
[11-11-04 18:38:05.8090] Slim::Utils::Scanner::Local::new (773) Handling new audio track file:///media/sdb1/music2/SR/Alexander%20Solsjenitsyn%20-%20En%20dag%20i%20Ivan%20Denisovitjs%20liv%20[Radioteatern].m4b
[11-11-04 18:38:05.8142] Slim::Utils::Scanner::Local::__ANON__ (793) ERROR SCANNING audio file file:///media/sdb1/music2/SR/Alexander%20Solsjenitsyn%20-%20En%20dag%20i%20Ivan%20Denisovitjs%20liv%20[Radioteatern].m4b: Unable to read tags from file
[11-11-04 18:38:07.4443] Slim::Utils::Scanner::Local::markDone (999) Finished scan type 2 for /media/sdb1/music2
[11-11-04 18:38:07.4464] Slim::Utils::Scanner::Local::markDone (1017) All rescan tasks finished (total changes: 76)
[11-11-04 18:38:08.0039] Slim::Music::Artwork::precacheAllArtwork (442) Starting precacheArtwork for 5 albums
[11-11-04 18:38:08.4756] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Slip Sliding Away from /media/sdb1/music2/Lossles Music/Elmore James - Slip Sliding Away/The Sky Is Crying.flac
[11-11-04 18:38:08.5846] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Golden Age of Christmas Music from /media/sdb1/music2/Lossles Music/The Golden Age of Christmas Music [FLAC]/The Golden Age of Christmas Music Disc 1/21 - Brenda Lee - Rocking Around.flac
[11-11-04 18:38:08.7026] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Complete Riverside Recordings from /media/sdb1/music2/Lossles Music/Bill Evans - The Complete Riverside Recordings/Bill Evans (1962) The Complete Riverside Recordings (Disc 7)/Waltz For Debby (take 2).flac
[11-11-04 18:38:08.8045] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for The Complete Live at the Plugged Nickel 1965 from /media/sdb1/music2/Lossles Music/Miles Davis - The Complete Live at the Plugged Nickel (1965)(1995 Remaster)/Miles Davis - The Complete Live at the Plugged Nickel _2b/04 - The Theme.flac
[11-11-04 18:38:09.0648] Slim::Music::Artwork::__ANON__ (531) Pre-caching artwork for Mäster Cees memoarer from /media/sdb1/music2/Bamse/Cornelis Vreeswijk (1993) Mäster Cees memoarer/Cornelis Vreeswijk (1993) Mäster Cees memoarer - Vol.1/20 - På grund av emigration.flac
[11-11-04 18:38:09.1668] Slim::Music::Artwork::__ANON__ (547) precacheArtwork finished in 1.16029906272888
[11-11-04 18:38:09.1686] Slim::Utils::Scanner::Local::__ANON__ (1036) Scanner made 76 changes, updating last rescan timestamp
[11-11-04 18:38:09.1830] Slim::Utils::Scanner::Local::__ANON__ (1044) Scan change count reached 148, optimizing database
[11-11-04 18:38:09.1849] Slim::Schema::optimizeDB (436) Start schema_optimize
[11-11-04 18:38:19.7899] Slim::Schema::optimizeDB (450) End schema_optimize
[11-11-04 18:38:20.0613] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
[11-11-04 18:38:22.7809] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
Comment 19 Mikael Nyberg 2011-11-04 11:51:43 UTC
Now with vidoes and pictures a "scan for new and changed" gives this intersting log very similar to the crashing ones but it works ?

new equeue_entry @ 0xf2a8d70 (type 2, data @ 0xef6a308)
thread_signal -> 36
Finished scanning
new equeue_entry @ 0xeaec040 (type 4, data @ (nil))
thread_signal -> 36
thread_signal_read <- 23 waiting...
thread_signal_read <- 23 OK
Got thread event, type 2 @ 0xe9e08c8
Got thread event, type 2 @ 0xf27cdf8
[11-11-04 19:39:04.2099] Slim::Utils::Scanner::LMS::__ANON__ (260) Scanning new media files (10)
Got thread event, type 2 @ 0xef6a308
Got thread event, type 4 @ (nil)
[11-11-04 19:39:04.2139] Slim::Utils::Scanner::LMS::__ANON__ (281) Finished scanning
Waiting for thread 0xb5effba0 to stop...
Thread stopped
[11-11-04 19:39:04.4824] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
[11-11-04 19:39:04.4890] Slim::Schema::wipeCaches (2067) Wiped all in-memory caches.
[11-11-04 19:39:04.4915] Slim::Music::Import::nextScanTask (702) remaining scans in queue:()
Comment 20 Mikael Nyberg 2011-11-04 12:52:40 UTC
And now with all plugins reinstalled , it works .

I can not get it to segfault.

1. When scanning a selected music folder

2. Doing a scan for new and changed .

And it does work on rescan for video and pictures .

Scan for new and changed is closest to the old behaviur thou.

Now Erland and Michael can have a say ? is it fixed for you ?
Comment 21 Andy Grundman 2011-11-20 15:37:49 UTC
Seems fixed.