Alright. Back home. Here’s what I did:
/etc/init.d/mt-daapd stop
> /var/log/mt-daapd.log
rm /usr/var/cache/mt-daapd/*So, in other words, I stopped the daemon, emptied the log file and also deleted the database.
The beginning of the log that I posted below is quite long — it’s because it complained it can’t find the database and it’s rebuilding it. Everything there seems fine to me.
Then, the last lines.
root@godzilla:/tmp# head -n 140 /var/log/mt-daapd.log
2009-05-29 21:35:16 (b74386c0): Firefly Version svn-1696: Starting with debuglevel 9
2009-05-29 21:35:16 (b74386c0): Attempting to load plugin /usr/lib/mt-daapd/plugins/out-daap.so
2009-05-29 21:35:16 (b74386c0): Loaded plugin /usr/lib/mt-daapd/plugins/out-daap.so (daap/svn-1696)
2009-05-29 21:35:16 (b74386c0): New transcode codec list:
2009-05-29 21:35:16 (b74386c0): Attempting to load plugin /usr/lib/mt-daapd/plugins/rsp.so
2009-05-29 21:35:16 (b74386c0): Loaded plugin /usr/lib/mt-daapd/plugins/rsp.so (rsp/svn-1696)
2009-05-29 21:35:16 (b74386c0): New transcode codec list:
2009-05-29 21:35:16 (b74386c0): Attempting to load plugin /usr/lib/mt-daapd/plugins/ssc-script.so
2009-05-29 21:35:16 (b74386c0): No ssc program specified for script transcoder.
2009-05-29 21:35:16 (b74386c0): Error loading plugin /usr/lib/mt-daapd/plugins/ssc-script.so: plugin declined to load
2009-05-29 21:35:16 (b74386c0): Attempting to load plugin /usr/lib/mt-daapd/plugins/ssc-ffmpeg.so
2009-05-29 21:35:16 (b74386c0): Loaded plugin /usr/lib/mt-daapd/plugins/ssc-ffmpeg.so (ssc-ffmpeg/svn-1696)
2009-05-29 21:35:16 (b74386c0): New transcode codec list: flac,alac,ogg,wma
2009-05-29 21:35:16 (b74386c0): Plugin loaded: ssc-ffmpeg/svn-1696
2009-05-29 21:35:16 (b74386c0): Plugin loaded: rsp/svn-1696
2009-05-29 21:35:16 (b74386c0): Plugin loaded: daap/svn-1696
2009-05-29 21:35:16 (b74386c0): Starting rendezvous daemon
2009-05-29 21:35:16 (b74386c0): Starting rendezvous services
2009-05-29 21:35:16 (b74386c0): Starting signal handler
2009-05-29 21:35:16 (b74386c0): Pid: 6370
2009-05-29 21:35:16 (b74386c0): Opening database
2009-05-29 21:35:16 (b74386c0): Executing: select value from config where term='version'
2009-05-29 21:35:16 (b74386c0): Creating new db handle
2009-05-29 21:35:16 (b74386c0): Error: enum_begin failed (error 1): Misc SQL Error: no such table: config
2009-05-29 21:35:16 (b74386c0): Can't get db version. New database?
2009-05-29 21:35:16 (b74386c0): Results: 0
2009-05-29 21:35:16 (b74386c0): Initializing database
2009-05-29 21:35:16 (b74386c0): Executing: select count(*) FROM songs
2009-05-29 21:35:16 (b74386c0): Error: enum_begin failed (error 1): ?
2009-05-29 21:35:16 (b74386c0): Executing: select value from config where term='rescan'
2009-05-29 21:35:16 (b74386c0): Error: enum_begin failed (error 1): ?
2009-05-29 21:35:16 (b74386c0): Full reload...
2009-05-29 21:35:16 (b74386c0): Executing: drop index idx_path
2009-05-29 21:35:16 (b74386c0): Query: drop index idx_path
2009-05-29 21:35:16 (b74386c0): Error: no such index: idx_path
2009-05-29 21:35:16 (b74386c0): Executing: drop index idx_songid
2009-05-29 21:35:16 (b74386c0): Query: drop index idx_songid
2009-05-29 21:35:16 (b74386c0): Error: no such index: idx_songid
2009-05-29 21:35:16 (b74386c0): Executing: drop index idx_playlistid
2009-05-29 21:35:16 (b74386c0): Query: drop index idx_playlistid
2009-05-29 21:35:16 (b74386c0): Error: no such index: idx_playlistid
2009-05-29 21:35:16 (b74386c0): Executing: drop table songs
2009-05-29 21:35:16 (b74386c0): Query: drop table songs
2009-05-29 21:35:16 (b74386c0): Error: no such table: songs
2009-05-29 21:35:16 (b74386c0): Executing: delete from playlists where not type=1 and not type=0
2009-05-29 21:35:16 (b74386c0): Query: delete from playlists where not type=1 and not type=0
2009-05-29 21:35:16 (b74386c0): Error: no such table: playlists
2009-05-29 21:35:16 (b74386c0): Executing: drop table playlistitems
2009-05-29 21:35:16 (b74386c0): Query: drop table playlistitems
2009-05-29 21:35:16 (b74386c0): Error: no such table: playlistitems
2009-05-29 21:35:16 (b74386c0): Executing: drop table config
2009-05-29 21:35:16 (b74386c0): Starting polling thread
2009-05-29 21:35:16 (b74386c0): Query: drop table config
2009-05-29 21:35:16 (b74386c0): Error: no such table: config
2009-05-29 21:35:16 (b74386c0): Executing: vacuum
2009-05-29 21:35:16 (b6becb90): Waiting for data
2009-05-29 21:35:16 (b74386c0): Entering runloop
2009-05-29 21:35:16 (b74386c0): Rows: 0
2009-05-29 21:35:16 (b74386c0): Executing: create table songs (
id INTEGER PRIMARY KEY NOT NULL,
path VARCHAR(4096) NOT NULL,
fname VARCHAR(255) NOT NULL,
title VARCHAR(1024) DEFAULT NULL,
artist VARCHAR(1024) DEFAULT NULL,
album VARCHAR(1024) DEFAULT NULL,
genre VARCHAR(255) DEFAULT NULL,
comment VARCHAR(4096) DEFAULT NULL,
type VARCHAR(255) DEFAULT NULL,
composer VARCHAR(1024) DEFAULT NULL,
orchestra VARCHAR(1024) DEFAULT NULL,
conductor VARCHAR(1024) DEFAULT NULL,
grouping VARCHAR(1024) DEFAULT NULL,
url VARCHAR(1024) DEFAULT NULL,
bitrate INTEGER DEFAULT 0,
samplerate INTEGER DEFAULT 0,
song_length INTEGER DEFAULT 0,
file_size INTEGER DEFAULT 0,
year INTEGER DEFAULT 0,
track INTEGER DEFAULT 0,
total_tracks INTEGER DEFAULT 0,
disc INTEGER DEFAULT 0,
total_discs INTEGER DEFAULT 0,
bpm INTEGER DEFAULT 0,
compilation INTEGER DEFAULT 0,
rating INTEGER DEFAULT 0,
play_count INTEGER DEFAULT 0,
data_kind INTEGER DEFAULT 0,
item_kind INTEGER DEFAULT 0,
description INTEGER DEFAULT 0,
time_added INTEGER DEFAULT 0,
time_modified INTEGER DEFAULT 0,
time_played INTEGER DEFAULT 0,
db_timestamp INTEGER DEFAULT 0,
disabled INTEGER DEFAULT 0,
sample_count INTEGER DEFAULT 0,
force_update INTEGER DEFAULT 0,
codectype VARCHAR(5) DEFAULT NULL,
idx INTEGER NOT NULL,
has_video INTEGER DEFAULT 0,
contentrating INTEGER DEFAULT 0,
bits_per_sample INTEGER DEFAULT 0,
album_artist VARCHAR(1024)
);
create table playlistitems (
id INTEGER PRIMARY KEY NOT NULL,
playlistid INTEGER NOT NULL,
songid INTEGER NOT NULL
);
create table config (
term VARCHAR(255) NOT NULL,
subterm VARCHAR(255) DEFAULT NULL,
value VARCHAR(1024) NOT NULL
);
insert into config values ('version','','13');
2009-05-29 21:35:16 (b74386c0): Rows: 1
2009-05-29 21:35:16 (b74386c0): Executing: create table playlists (
id INTEGER PRIMARY KEY NOT NULL,
title VARCHAR(255) NOT NULL,
type INTEGER NOT NULL,
items INTEGER NOT NULL,
query VARCHAR(1024),
db_timestamp INTEGER NOT NULL,
path VARCHAR(4096),
idx INTEGER NOT NULL
);
insert into playlists values (1,'Library',1,0,'1',0,'',0);
2009-05-29 21:35:16 (b74386c0): Rows: 1
2009-05-29 21:35:16 (b74386c0): Executing: select count(*) FROM songs
2009-05-29 21:35:16 (b74386c0): Starting mp3 scan
2009-05-29 21:35:16 (b74386c0): Starting scan_init
2009-05-29 21:35:16 (b74386c0): Starting db scan
2009-05-29 21:35:16 (b74386c0): Executing: pragma synchronous = off
2009-05-29 21:35:16 (b74386c0): Rows: 1
2009-05-29 21:35:16 (b74386c0): Executing: begin transaction
2009-05-29 21:35:16 (b74386c0): Rows: 1
2009-05-29 21:35:16 (b74386c0): Scanning for MP3s in /media/storage
2009-05-29 21:35:16 (b74386c0): Found /media/storage/outer limits
2009-05-29 21:35:16 (b74386c0): Found outer limits.. recursing
[..]
root@godzilla:/tmp# tail -n 30 /var/log/mt-daapd.log
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/h713std.reg
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/csnpstd.dll
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/snpstd.ds
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/rsnpstd.dll
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/o665std.reg
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/o763std.reg
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/snpstd.ini
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/snpstd.cat
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/snpstd.sys
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/m033std.reg
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/m034std.reg
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/SnxDSC.ini
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/vsnpstd.dll
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/dsnpstd.ax
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/snpstd/TwainUI.dll
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield
2009-05-29 21:41:31 (b74386c0): Found InstallShield.. recursing
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional
2009-05-29 21:41:31 (b74386c0): Found Professional.. recursing
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional/RunTime
2009-05-29 21:41:31 (b74386c0): Found RunTime.. recursing
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional/RunTime/09
2009-05-29 21:41:31 (b74386c0): Found 09.. recursing
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional/RunTime/09/00
2009-05-29 21:41:31 (b74386c0): Found 00.. recursing
2009-05-29 21:41:31 (b74386c0): Found /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional/RunTime/09/00/Intel32
2009-05-29 21:41:31 (b74386c0): Found Intel32.. recursing
2009-05-29 21:41:31 (b6becb90): Received a message from daap server
2009-05-29 21:41:31 (b6becb90): Rendezvous socket closed (daap server crashed?) Aborting.
2009-05-29 21:41:31: Aborting
Right, so I thought there might be something wrong with that directory. Nothing, really, apart of the fact that the path is rather long:
root@godzilla:/tmp# ll /home/alex/.wine/drive_c/Program Files/Common Files/InstallShield/Professional/RunTime/09/00/Intel32/
total 1752
-rw-r--r-- 1 alex alex 69715 2003-09-03 02:27 ctor.dll
-rwxr-xr-x 1 alex alex 5632 2003-09-03 02:25 DotNetInstaller.exe
-rw-r--r-- 1 alex alex 184452 2008-05-24 01:00 iGdi.dll
-rw-r--r-- 1 alex alex 724992 2003-09-03 02:28 iKernel.dll
-rw-r--r-- 1 alex alex 266240 2003-09-03 02:26 iscript.dll
-rw-r--r-- 1 alex alex 192512 2003-09-03 02:26 iuser.dll
-rw-r--r-- 1 alex alex 311428 2008-05-24 01:00 Setup.dll
The directory itself has adequate permissions, as well:
drwxr-xr-x 2 alex alex 4096 2008-05-24 01:00 Intel32How can I stress this enough? That directory should *not* have been scanned in the first place! mt-daapd chooses to bypass the mp3_dir variable, as I already said in another post.