Bug 4299 - Slimserver crash after upgrade to 6.5
: Slimserver crash after upgrade to 6.5
Status: CLOSED WORKSFORME
Product: Logitech Media Server
Classification: Unclassified
Component: Windows Service
: 6.5.0
: PC Windows XP
: P2 normal (vote)
: ---
Assigned To: Chris Owens
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2006-10-05 10:56 UTC by Dan Evans
Modified: 2008-12-18 11:12 UTC (History)
3 users (show)

See Also:
Category: ---


Attachments
event log from crash after update to 6.5 (511.84 KB, application/octet-stream)
2006-10-05 10:57 UTC, Dan Evans
Details
command prompt output (18.44 KB, text/plain)
2006-11-01 14:37 UTC, Ross Levine
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Dan Evans 2006-10-05 10:56:21 UTC
(reference tmid 10342)

Customer is unable to get Slimserver running successfully after upgrading to 6.5.  Under all conditions we've tried it crashes.

 * He's getting a new error message:  "Starting Slimserver failed.  Please see the event viewer and contact support."

 * his first attempt to upgrade left slimserver in the stuck on starting state.  had him remove and reinstall.

 * second install came up with errors: (attached event log is from this attempt)

C:\DOCUME~1\LARRY\LOCALS~1\Temp\WER2e30.dir00\slim.exe.mdmp
C:\DOCUME~1\LARRY\LOCALS~1\Temp\WER2e30.dir00\appcompat.txt

 * third attempt repeated first finding-- stuck on slimserver starting

 * tested on a different computer of his but got the following errors in event log:

C:\PROGRA~1\SLIMSE~1\server\Bin\MSWIN3~1\mysqld.exe: ready for connections.
Version: '5.0.22-community-nt'	socket: ''  port: 9092	MySQL Community
Edition (GPL)

Panic: cannot crete singleton 'Perltray_slimtray.pl"

The description for Event ID ( 0 ) in Source ( Application ) cannot be
found. The local computer may not have the necessary registry information or
message DLL files to display messages from a remote computer. You may be
able to use the /AUXSOURCE= flag to retrieve this description; see Help and
Support for details. The following information is part of the event:
CreateService failed: The specified service already exists

 * In case it was his music library that was crashing the server, I had him install with a test folder with a single album.  still no luck.  He reverted down to 6.3 and it installs and runs fine.
Comment 1 Dan Evans 2006-10-05 10:57:06 UTC
Created attachment 1621 [details]
event log from crash after update to 6.5
Comment 2 Dan Sully 2006-10-05 15:24:27 UTC
There's no useful information in that event log.

Please open have SlimServer start, and have the scanner crash.

Then open a cmd window, cd C:\Program Files\SlimServer\server

and run scanner.exe --wipe --d_import --d_server --d_scan

Also, try with 6.5.1

Is the Music Folder set to a network drive? iTunes? MusicMagic?

The SlimTray error sounds like another copy is running. Open Task Manager and make sure there is only one copy running, or kill the existing copy and start it again.
Comment 3 Dan Evans 2006-10-06 11:45:12 UTC
Output from one user's test (tmid 10660)

= = = = =

C:\Program Files\SlimServer\server>scanner.exe --wipe --d_import --d_server
--d_
scan
2006-10-06 08:17:57.4218 SlimServer OSDetect init...
2006-10-06 08:17:57.4220 SlimServer OS Specific init...
2006-10-06 08:17:57.4220 SlimServer settings init...
2006-10-06 08:17:57.4676 SlimServer strings init...
2006-10-06 08:17:57.9218 SlimServer Info init...
2006-10-06 08:17:58.9687 ERROR: DBI Connection failed: DBI
connect('hostname=127
.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't
connect to
 MySQL server on '127.0.0.1' (10061) at C:\Program
Files\SlimServer\server\CPAN/
DBIx/Class/Storage/DBI.pm line 652

2006-10-06 08:17:58.9690 ERROR: Backtrace follows:

2006-10-06 08:17:58.9693 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program
Files\SlimServer\
server\CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program
Files\SlimServer\ser
ver\CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program
Files\SlimServe
r\server\CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimSe
rver\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (/PerlApp/Slim/Schema.pm line
118)
   frame 5: Slim::Schema::init (/PerlApp/Slim/Music/Info.pm line 71)
   frame 6: Slim::Music::Info::init (scanner.pl line 239)
   frame 7: main::initializeFrameworks (scanner.pl line 86)
   frame 8: main::main (scanner.pl line 330)

Carp::Clan::__ANON__(): DBI Connection failed: DBI
connect('hostname=127.0.0.1;p
ort=9092;database=slimserver','slimserver',...) failed: Can't connect to
MySQL s
erver on '127.0.0.1' (10061) at C:\Program
Files\SlimServer\server\CPAN/DBIx/Cla
ss/Storage/DBI.pm line 652
2006-10-06 08:17:58.9707 Got to the END.
2006-10-06 08:17:58.9708 Got sigint.
2006-10-06 08:17:58.9709 SlimServer cleaning up.
2006-10-06 08:18:00.0625 ERROR: DBI Connection failed: DBI
connect('hostname=127
.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't
connect to
 MySQL server on '127.0.0.1' (10061) at C:\Program
Files\SlimServer\server\CPAN/
DBIx/Class/Storage/DBI.pm line 652

2006-10-06 08:18:00.0628 ERROR: Backtrace follows:

2006-10-06 08:18:00.0632 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program
Files\SlimServer\
server\CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program
Files\SlimServer\ser
ver\CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program
Files\SlimServe
r\server\CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimSe
rver\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (C:\Program
Files\SlimServer\server\C
PAN/DBIx/Class/Storage/DBI.pm line 677)
   frame 5: DBIx::Class::Storage::DBI::txn_begin
(/PerlApp/DBIx/Class/Schema.pm
line 487)
   frame 6: DBIx::Class::Schema::txn_begin (/PerlApp/DBIx/Class/Schema.pm
line 5
67)
   frame 7: DBIx::Class::Schema::txn_do (scanner.pl line 253)
   frame 8: (eval) (scanner.pl line 245)
   frame 9: main::setIsScanning (scanner.pl line 321)
   frame 10: main::cleanup (/PerlApp/Slim/bootstrap.pm line 300)
   frame 11: Slim::bootstrap::sigint (/PerlApp/Slim/bootstrap.pm line 331)
   frame 12: Slim::bootstrap::END (scanner.pl line 0)
   frame 13: (eval) (scanner.pl line 0)

2006-10-06 08:18:00.0647 ERROR: Scanner: Failed to update isScanning:
[Carp::Cla
n::__ANON__(): DBI Connection failed: DBI
connect('hostname=127.0.0.1;port=9092;
database=slimserver','slimserver',...) failed: Can't connect to MySQL server
on
'127.0.0.1' (10061) at C:\Program
Files\SlimServer\server\CPAN/DBIx/Class/Storag
e/DBI.pm line 652
]

2006-10-06 08:18:00.0651 ERROR: forceCommit: Trying to commit transactions
befor
e DB is initialized!

2006-10-06 08:18:01.1718 ERROR: DBI Connection failed: DBI
connect('hostname=127
.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't
connect to
 MySQL server on '127.0.0.1' (10061) at C:\Program
Files\SlimServer\server\CPAN/
DBIx/Class/Storage/DBI.pm line 652

2006-10-06 08:18:01.1720 ERROR: Backtrace follows:

2006-10-06 08:18:01.1723 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program
Files\SlimServer\
server\CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program
Files\SlimServer\ser
ver\CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program
Files\SlimServe
r\server\CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimSe
rver\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (/PerlApp/Slim/Schema.pm line
247)
   frame 5: (eval) (/PerlApp/Slim/Schema.pm line 247)
   frame 6: Slim::Schema::disconnect (scanner.pl line 324)
   frame 7: main::cleanup (/PerlApp/Slim/bootstrap.pm line 300)
   frame 8: Slim::bootstrap::sigint (/PerlApp/Slim/bootstrap.pm line 331)
   frame 9: Slim::bootstrap::END (scanner.pl line 0)
   frame 10: (eval) (scanner.pl line 0)


C:\Program Files\SlimServer\server>
Comment 4 Dan Evans 2006-10-09 10:13:27 UTC
Output from another user's test (tmid 10342)

"Error: Cannot connect to program"
Comment 5 Kevin Pearsall 2006-10-09 10:24:06 UTC
DS:  Do you have any other ideas / is this the input you were looking for?
Comment 6 Dan Sully 2006-10-09 11:06:26 UTC
Did the user have SlimServer started in another Window (via slim.exe or SlimTray) before trying to run scanner.exe?

The output looks like slimserver was not up, so that's the expected output.
Comment 7 Dan Evans 2006-10-09 15:07:24 UTC
Subject: Re:  Slimserver crash after upgrade to 6.5

Here is the text of the e-mail I sent to the 4 users who are having this 
crash...  let me know how you'd like it changed or what additions you'd 
like and I'll resend it.


Our engineers have a test they'd like to try.  Can you try the following:

  * On your PC, can you open a DOS window?    Go to Start > Run, type
"cmd" and click Okay.

In the DOS box, change directories to the Slimserver install directory,
in particular where the server executable is.

The default is C:\Program Files\Slimserver\Server.  So, type:

   > cd \

   > cd Program Files

   > cd Slimserver

   > cd server

Next, type in

   > scanner.exe --wipe --d_import --d_server --d_scan

This starts the scanner manually with text output.  If it hits an error,
this will show the error message.

Let me know what you find.

Comment 8 Kevin Pearsall 2006-10-10 16:30:49 UTC
Dan S:  We're still pretty stumped on this, and Dan has 4 customers with the same issue.  What's the next step?  Could you elaborate a bit on exactly what we should get from the customers?
Comment 9 Dan Sully 2006-10-10 17:21:01 UTC
You've still not followed my instructions.

Make sure SlimServer is running. Then have the user run scanner.exe with the debug options, and:

--logfile=C:\slimserver.log

and have those logs attached to this bug, and any output from the scanner.exe itself, things like segfault, couldn't scan, etc.
Comment 10 Chris Owens 2006-10-11 10:51:55 UTC
Still waiting on additional info.
Comment 11 Ross Levine 2006-10-31 15:08:31 UTC
I too have a couple customers with this bug. DS, it seems DE has indeed provided the output you're looking for, he pasted it into the body of comment #3. Do you want similiar output from other people? 

I have a customer that claims he's identified a playlist that is causing the scanner crash, he said he'd email it to me. I'll attach it with what I find when it arrives. 
Comment 12 Dan Sully 2006-10-31 15:57:22 UTC
Is anyone reading what I say?

Comment #3 is invalid as far as I am concerned, as it never says if SlimServer has been started first, before the scanner is run manually.

Please see comment #2 for the instructions and comment #9 for an additional command line argument.

Thanks.

Comment 13 Ross Levine 2006-11-01 14:31:55 UTC
I'm reading what you say, but I guess I didn't understand. Please forgive my moronory. 

TMID 12772. He seems to believe he's narrowed it down to some .aa files which he says are audible books of some kind. He's removed those files and now he's not seeing the scanner crash. I'm trying to get those files from him to see if I can reproduce his particular crash. 

Does this log help at all?

2006-10-31 17:51:24.4687 SlimServer OSDetect init...
2006-10-31 17:51:24.4689 SlimServer OS Specific init...
2006-10-31 17:51:24.4689 SlimServer settings init...
2006-10-31 17:51:24.7289 SlimServer strings init...
2006-10-31 17:51:25.3750 SlimServer Info init...
2006-10-31 17:51:26.5625 ERROR: DBI Connection failed: DBI
connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on
'127.0.0.1' (10061) at C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652

2006-10-31 17:51:26.5627 ERROR: Backtrace follows:

2006-10-31 17:51:26.5629 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line
616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (/PerlApp/Slim/Schema.pm line 118)
   frame 5: Slim::Schema::init (/PerlApp/Slim/Music/Info.pm line 71)
   frame 6: Slim::Music::Info::init (scanner.pl line 240)
   frame 7: main::initializeFrameworks (scanner.pl line 87)
   frame 8: main::main (scanner.pl line 312)

Carp::Clan::__ANON__(): DBI Connection failed: DBI
connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on
'127.0.0.1' (10061) at C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652
2006-10-31 17:51:26.5634 Got to the END.
2006-10-31 17:51:26.5713 Got sigint.
2006-10-31 17:51:26.5796 SlimServer scanner cleaning up.
2006-10-31 17:51:27.5625 ERROR: DBI Connection failed: DBI
connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on
'127.0.0.1' (10061) at C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652

2006-10-31 17:51:27.5626 ERROR: Backtrace follows:

2006-10-31 17:51:27.5630 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line
616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 677)
   frame 5: DBIx::Class::Storage::DBI::txn_begin (/PerlApp/DBIx/Class/Schema.pm line 487)
   frame 6: DBIx::Class::Schema::txn_begin (/PerlApp/DBIx/Class/Schema.pm line 567)
   frame 7: DBIx::Class::Schema::txn_do (/PerlApp/Slim/Music/Import.pm line 241)
   frame 8: (eval) (/PerlApp/Slim/Music/Import.pm line 233)
   frame 9: Slim::Music::Import::setIsScanning (scanner.pl line 303)
   frame 10: main::cleanup (/PerlApp/Slim/bootstrap.pm line 300)
   frame 11: Slim::bootstrap::sigint (/PerlApp/Slim/bootstrap.pm line 331)
   frame 12: Slim::bootstrap::END (scanner.pl line 0)
   frame 13: (eval) (scanner.pl line 0)

2006-10-31 17:51:27.5633 ERROR: Scanner: Failed to update isScanning: [Carp::Clan::__ANON__(): DBI Connection failed:
DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server
on '127.0.0.1' (10061) at C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652
]

2006-10-31 17:51:27.5633 ERROR: forceCommit: Trying to commit transactions before DB is initialized!

2006-10-31 17:51:28.5625 ERROR: DBI Connection failed: DBI
connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on
'127.0.0.1' (10061) at C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652

2006-10-31 17:51:28.5627 ERROR: Backtrace follows:

2006-10-31 17:51:28.5630 Backtrace:

   frame 0: Slim::Schema::Storage::throw_exception (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 658)
   frame 1: DBIx::Class::Storage::DBI::_connect (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line
616)
   frame 2: DBIx::Class::Storage::DBI::_populate_dbh (C:\Program Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm
line 543)
   frame 3: DBIx::Class::Storage::DBI::ensure_connected (C:\Program
Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 557)
   frame 4: DBIx::Class::Storage::DBI::dbh (/PerlApp/Slim/Schema.pm line 247)
   frame 5: (eval) (/PerlApp/Slim/Schema.pm line 247)
   frame 6: Slim::Schema::disconnect (scanner.pl line 306)
   frame 7: main::cleanup (/PerlApp/Slim/bootstrap.pm line 300)
   frame 8: Slim::bootstrap::sigint (/PerlApp/Slim/bootstrap.pm line 331)
   frame 9: Slim::bootstrap::END (scanner.pl line 0)
   frame 10: (eval) (scanner.pl line 0)
Comment 14 Dan Sully 2006-11-01 14:34:12 UTC
Subject: Re:  Slimserver crash after upgrade to 6.5

* Slim Devices Bugzilla shaped the electrons to say...

>TMID 12772. He seems to believe he's narrowed it down to some .aa files which
>he says are audible books of some kind. He's removed those files and now he's
>not seeing the scanner crash. I'm trying to get those files from him to see if
>I can reproduce his particular crash. 
>
>Does this log help at all?
>
>2006-10-31 17:51:24.4687 SlimServer OSDetect init...
>2006-10-31 17:51:24.4689 SlimServer OS Specific init...
>2006-10-31 17:51:24.4689 SlimServer settings init...
>2006-10-31 17:51:24.7289 SlimServer strings init...
>2006-10-31 17:51:25.3750 SlimServer Info init...
>2006-10-31 17:51:26.5625 ERROR: DBI Connection failed: DBI
>connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...)
>failed: Can't connect to MySQL server on
>'127.0.0.1' (10061) at C:\Program
>Files\SlimServer\server\CPAN/DBIx/Class/Storage/DBI.pm line 652

Removing those files won't solve the issue of the scanner not being able to
connect to the database.

-D
--
Just because they're complaining doesn't mean they're right.

Comment 15 Ross Levine 2006-11-01 14:37:34 UTC
Created attachment 1684 [details]
command prompt output
Comment 16 Ross Levine 2006-11-01 14:38:37 UTC
Just attached another log, TMID 12803 is having a similar problem. 

DS is this what you're looking for? Let me know if you need more, or something else. 
Comment 17 Dan Sully 2006-11-01 14:48:32 UTC
Ross - can you please take a look through that log that you just uploaded?

See anything wrong there? Nope.. neither do I. No warning messages, just adding mp3s to the database.

Come on guys, help me out a little here and use your heads.

Don't just blindly forward logs and stuff without even giving them a cursory look.

Get to know the data, and what it means, either benign or error ridden.

Thanks
Comment 18 KDF 2006-11-01 15:01:57 UTC
re-reading the original report, why are we now stuck on scanning problems?

other bugs cover scanning issues specifically:
bug 4416
bug 4423
bug 4439 (essentially a dupe of 4423)
Comment 19 Dan Sully 2006-11-01 15:15:13 UTC
Guys - I'm sorry for the harsh tone there.

Is the customer in the ticket still having this issue?

Ross - can you look at the bugs that KDF posted to see if the scanning problem is part of one of those?

This bug is now long and twisted and it's hard to gather any useful data from the disjointed comments.
Comment 20 Chris Owens 2007-10-22 10:30:42 UTC
Please let me know if this needs to be reopened.
Comment 21 Chris Owens 2008-03-07 09:03:56 UTC
This bug is being closed since it was resolved for a version which is now released!  Please download the new version of SqueezeCenter (formerly SlimServer) at http://www.slimdevices.com/su_downloads.html

If you are still seeing this bug, please re-open it and we will consider it for a future release.