Can’t start 1376…

[Dave.B]

I thought I would upgrade from 1359 to 1376 as part of my troubleshooting this infernal connection loss problem (I know Ron said it’s not Firefly, but I’m clutching at straws now).

Anyway, I’ve upgraded loads of times before and never had a problem, and I’ve done nothing differently this time.

I did get 1376 up and running, but it only found 9113 songs out of ~13,000. They are split roughly 1/3 -> 2/3 mp3 to WMA so I gues it was only finding wma files despite mp3 being in the list.

Anyway, I decided to wipe the db and restart to try and correct it. Big mistake. Now it won’t start at all. From the log:

# cat /var/log/mt-daapd.log

2006-09-16 14:36:24 (00000400): Starting with debuglevel 2

2006-09-16 14:36:24 (00000400): Starting rendezvous daemon

2006-09-16 14:36:25 (00000400): mDNSPlatformTimeNow went backwards by 4 ticks; s

etting correction factor to 4

2006-09-16 14:36:25 (00000400): Starting signal handler

2006-09-16 14:36:25 (00000402): Signal handler started

2006-09-16 14:37:01 (00000400): Error: enum_begin failed (error 1): Misc SQL Err

or: database is locked

2006-09-16 14:37:01 (00000400): Can't get db version. New database?

2006-09-16 14:37:01 (00000400): Initializing database

2006-09-16 14:37:37 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:38:13 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:38:13 (00000400): Full reload...

2006-09-16 14:41:43 (00000400): Starting with debuglevel 2

2006-09-16 14:41:43 (00000400): Starting rendezvous daemon

2006-09-16 14:41:43 (00000400): mDNSPlatformTimeNow went backwards by 3 ticks; s

etting correction factor to 3

2006-09-16 14:41:43 (00000400): Starting signal handler

2006-09-16 14:41:43 (00000402): Signal handler started

2006-09-16 14:41:43 (00000400): db_sqlite2_open: file is encrypted or is not a d

atabase (/opt/var/mt-daapd/songs.db)

2006-09-16 14:41:43 (00000400): Error opening db: Misc SQL Error: file is encryp

ted or is not a database

2006-09-16 14:41:43 (00000400): Stopping signal handler

2006-09-16 14:44:16 (00000400): Starting mp3 scan

2006-09-16 14:44:19 (00000400): Starting with debuglevel 2

2006-09-16 14:44:19 (00000400): Starting rendezvous daemon

2006-09-16 14:44:19 (00000400): mDNSPlatformTimeNow went backwards by 4 ticks; s

etting correction factor to 4

2006-09-16 14:44:19 (00000400): Starting signal handler

2006-09-16 14:44:19 (00000402): Signal handler started

2006-09-16 14:44:19 (00000400): Error: enum_begin failed (error 1): Misc SQL Err

or: no such table: config

2006-09-16 14:44:19 (00000400): Can't get db version. New database?

2006-09-16 14:44:19 (00000400): Initializing database

2006-09-16 14:44:19 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:44:19 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:44:19 (00000400): Full reload...

2006-09-16 14:44:23 (00000400): Starting mp3 scan

2006-09-16 14:44:56 (00000400): Query: pragma synchronous = off

2006-09-16 14:44:56 (00000400): Error: database is locked

2006-09-16 14:44:56: Aborting

2006-09-16 14:49:35 (00000400): Starting with debuglevel 2

2006-09-16 14:49:36 (00000400): Starting rendezvous daemon

2006-09-16 14:49:36 (00000400): mDNSPlatformTimeNow went backwards by 3 ticks; s

etting correction factor to 3

2006-09-16 14:49:36 (00000400): Starting signal handler

2006-09-16 14:49:36 (00000402): Signal handler started

2006-09-16 14:49:36 (00000400): Error: enum_begin failed (error 1): Misc SQL Err

or: no such table: config

2006-09-16 14:49:36 (00000400): Can't get db version. New database?

2006-09-16 14:49:36 (00000400): Initializing database

2006-09-16 14:49:36 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:49:36 (00000400): Error: enum_begin failed (error 1): ?

2006-09-16 14:49:36 (00000400): Full reload...

2006-09-16 14:49:43 (00000400): Starting mp3 scan

And that’s where it stops. The Slug and attached disk are doing nothing and the db filesize is not increasing. It’s been like that for half an hour now.

Help!

[rpedde]

@Dave.B wrote:

2006-09-16 14:41:43 (00000400): db_sqlite2_open: file is encrypted or is not a d
atabase (/opt/var/mt-daapd/songs.db)

That looks like there is an actual file there and it’s not a sqlite2 database. Is it possible you were using sqlite3?

Make sure there really isn’t anything in there and try it again. Meanwhile, I’ll try to repro this.

— Ron

[Dave.B]

I gave up in the end and went back to 1359, which started up perfectly first time.

every time I tried starting 1376 I did:

rm /opt/var/mt-daapd/songs.db

rm /opt/var/mt-daapd/songs.db-journal

ls -l

To make sure that the folder was indeed empty.

The database size did increase to 9612 (can’t remember what unit that is), and stayed there. It was 9612 every time I tried it (5 times).

Like I say, I then gave up, removed 1376 and installed 1359. As soon as I did that it kicked off a full rescan and staretd up perfectly.

I don’t think I was using sqlite3. I have a backup config file in the mp3 folder – still the only place I can edit as I still can’t get a text editor to work on the slug. I just copy that config file to the correct place when I need to change it (I always “cat” the file to check I copied it correctly). Same config file was used for all attempts at 1376, and the subsequent successful re-installation of 1359.

[rpedde]

@Dave.B wrote:

I gave up in the end and went back to 1359, which started up perfectly first time.

Hrm. Clearly I broke something. I’ll try and duplicate that.

— Ron

[Author]

Posts