OpenSolaris 0906 Server Log

[cudgel]

So I have Squeeze Center v7.4.2-29203 up and running on OpenSolaris 0906 with Perl 5.10 and it is currently scanning my library but there are a lot of error messages in the server log:

[09-11-10 11:01:08.9760] main::init (320) Starting Squeezebox Server (v7.4.2, r29203, Mon Nov 9 04:06:25 PST 2009) perl 5.010001
[09-11-10 11:01:35.6680] Slim::Utils::Misc::msg (1165) Warning: [11:01:35.6667] DBIx::Class::ResultSet::count(): Useless use of distinct on a grouped resultset ('distinct' is ignored when a 'group_by' is present) at /usr/local/slimserver/Slim/Control/Jive.pm line 195
[09-11-10 11:01:35.7176] Slim::Utils::Misc::msg (1165) Warning: [11:01:35.7168] DBIx::Class::ResultSet::count(): Useless use of distinct on a grouped resultset ('distinct' is ignored when a 'group_by' is present) at /usr/local/slimserver/Slim/Control/Jive.pm line 195
[09-11-10 11:02:01.1576] main::init (320) Starting Squeezebox Server (v7.4.2, r29203, Mon Nov 9 04:06:25 PST 2009) perl 5.010001
[09-11-10 11:02:05.8960] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.8944] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:05.8991] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.8983] Use of uninitialized value $entry in pattern match (m//) at /usr/local/slimserver/Slim/Control/Request.pm line 726.
[09-11-10 11:02:05.9004] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.8996] Use of uninitialized value $request in hash element at /usr/local/slimserver/Slim/Control/Request.pm line 762.
[09-11-10 11:02:05.9016] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.9009] Use of uninitialized value $args{"menu"} in hash element at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 138.
[09-11-10 11:02:05.9774] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.9766] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:05.9925] Slim::Utils::Misc::msg (1165) Warning: [11:02:05.9917] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0012] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0004] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0112] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0098] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0352] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0344] tempfile(): temporary filename requested but not opened.
Possibly unsafe, consider using tempfile() with OPEN set to true
[09-11-10 11:02:06.0469] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0462] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0502] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0494] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0552] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0544] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0583] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0576] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0619] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0611] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0652] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0645] Use of uninitialized value $entry in pattern match (m//) at /usr/local/slimserver/Slim/Control/Request.pm line 726.
[09-11-10 11:02:06.0663] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0657] Use of uninitialized value $request in hash element at /usr/local/slimserver/Slim/Control/Request.pm line 762.
[09-11-10 11:02:06.0676] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0669] Use of uninitialized value $args{"menu"} in hash element at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 138.
[09-11-10 11:02:06.0689] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0682] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0722] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0715] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0778] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0770] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.0877] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.0870] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.1074] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.1067] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.1489] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.1481] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.1528] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.1521] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.1568] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.1561] Use of uninitialized value $args{"type"} in string eq at /usr/local/slimserver/Slim/Plugin/OPMLBased.pm line 98.
[09-11-10 11:02:06.3538] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.3527] DBIx::Class::ResultSet::count(): Useless use of distinct on a grouped resultset ('distinct' is ignored when a 'group_by' is present) at /usr/local/slimserver/Slim/Control/Jive.pm line 195
[09-11-10 11:02:06.3809] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.3802] DBIx::Class::ResultSet::count(): Useless use of distinct on a grouped resultset ('distinct' is ignored when a 'group_by' is present) at /usr/local/slimserver/Slim/Control/Jive.pm line 195
[09-11-10 11:02:06.5679] main::checkDataSource (967) Warning: Schema updated or no tracks in the database, initiating scan.
[09-11-10 11:02:06.5767] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.5759] Use of uninitialized value in concatenation (.) or string at /usr/local/slimserver/Slim/Music/Import.pm line 161.
[09-11-10 11:02:06.5777] Slim::Utils::Misc::msg (1165) Warning: [11:02:06.5771] Use of uninitialized value in concatenation (.) or string at /usr/local/slimserver/Slim/Music/Import.pm line 161.

Are these normal or something to be concerned about? I did verify that I can play Internet radio last night but won't be able to verify that music plays until the scan completes and I'm back from work.

[cudgel]

Tested the install last night and again this morning after rebuilding DBD::mysql - every time I try to play a track or just add a track to a playlist (from the browser or via the remote) mysql crashes with the following output:

/usr/mysql/5.1/bin/mysqld'my_print_stacktrace+0x1c [0x874a108]
/usr/mysql/5.1/bin/mysqld'handle_segfault+0x1f3 [0x82be297]
/lib/libc.so.1'__sighndlr+0xf [0xd12ed0cf]
/lib/libc.so.1'call_user_handler+0x2af [0xd12e01bf]
/usr/mysql/5.1/bin/mysqld'__1cQbest_access_path6FpnEJOIN_pnNst_join_t able_pnDTHD_XIdd_v_+0x5a1 [0x83433e1]
/usr/mysql/5.1/bin/mysqld'__1cbGbest_extension_by_limited_search6FpnE JOIN_XIddII_b_+0x251 [0x8345335]
/usr/mysql/5.1/bin/mysqld'__1cbGbest_extension_by_limited_search6FpnE JOIN_XIddII_b_+0x58d [0x8345671]
/usr/mysql/5.1/bin/mysqld'__1cbGbest_extension_by_limited_search6FpnE JOIN_XIddII_b_+0x58d [0x8345671]
/usr/mysql/5.1/bin/mysqld'__1cLchoose_plan6FpnEJOIN_X_b_+0x2b3 [0x8344d77]
/usr/mysql/5.1/bin/mysqld'__1cUmake_join_statistics6FpnEJOIN_pnKTABLE _LIST_pnEItem_pnQst_dynamic_array__b_+0x16d9 [0x833f6c9]
/usr/mysql/5.1/bin/mysqld'__1cEJOINIoptimize6M_i_+0x5a0 [0x8339c20]
/usr/mysql/5.1/bin/mysqld'__1cMmysql_select6FpnDTHD_pppnEItem_pnKTABL E_LIST_IrnEList4n0B___p2IpnIst_order_9D39DXpnNsele ct_result_pnSst_select_lex_unit_pnNst_select_lex__ b_+0x363 [0x833de7b]
/usr/mysql/5.1/bin/mysqld'__1cNhandle_select6FpnDTHD_pnGst_lex_pnNsel ect_result_L_b_+0x11f [0x83385d3]
/usr/mysql/5.1/bin/mysqld'__1cVexecute_sqlcom_select6FpnDTHD_pnKTABLE _LIST__b_+0x20f [0x82d8f73]
/usr/mysql/5.1/bin/mysqld'__1cVmysql_execute_command6FpnDTHD__i_+0x49 7 [0x82d19ff]
/usr/mysql/5.1/bin/mysqld'__1cQdispatch_command6FnTenum_server_comman d_pnDTHD_pcI_b_+0x2418 [0x82cfc54]
/usr/mysql/5.1/bin/mysqld'__1cKdo_command6FpnDTHD__b_+0x105 [0x82cd695]
/usr/mysql/5.1/bin/mysqld'handle_one_connection+0x3b6 [0x82cba12]
/lib/libc.so.1'_thrp_setup+0x7e [0xd12ecd66]
/lib/libc.so.1'_lwp_start+0x0 [0xd12ecff0]
091111 9:23:36 - mysqld got signal 11 ;
This could be because you hit a bug. It is also possible that this binary
or one of the libraries it was linked against is corrupt, improperly built,
or misconfigured. This error can also be caused by malfunctioning hardware.
We will try our best to scrape up some info that will hopefully help diagnose
the problem, but since we have already crashed, something is definitely wrong
and this may fail.

key_buffer_size=16384
read_buffer_size=262144
max_used_connections=1
max_threads=151
threads_connected=1
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 49277 K
bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

thd: 0xb1d0648
Attempting backtrace. You can use the following information to find out
where mysqld died. If you see no messages after this, something went
terribly wrong...
stack_bottom = d10fdfb0 thread_stack 0x20000
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort...
thd->query at b206648 = SELECT me.id, me.url, me.content_type, me.title, me.titlesort, me.titlesearch, me.album, me.primary_artist, me.tracknum, me.timestamp, me.filesize, me.disc, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignment, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.musicbrainz_id, me.lossless, me.lyrics, me.replay_gain, me.replay_peak, me.extid FROM tracks me JOIN albums album ON album.id = me.album LEFT JOIN contributor_track contributorTracks ON contributorTracks.track = me.id LEFT JOIN contributors contributor ON contributor.id = contributorTracks.contributor WHERE ( ( audio = '1' AND contributor.id = '1' AND remote = '0' ) ) GROUP BY me.id ORDER BY concat(album.titlesort, '0'), me.disc, me.tracknum, concat(me.titlesort, '0')
thd->thread_id=3
thd->killed=NOT_KILLED
The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
information that should help you find out what is causing the crash.
091111 09:23:36 mysqld_safe mysqld restarted
091111 9:23:37 [Warning] option 'thread_stack': unsigned value 65536 adjusted to 131072
091111 9:23:37 [Warning] option 'thread_stack': unsigned value 65536 adjusted to 131072
InnoDB: The log sequence number in ibdata files does not match
InnoDB: the log sequence number in the ib_logfiles!
091111 9:23:37 InnoDB: Database was not shut down normally!
InnoDB: Starting crash recovery.
InnoDB: Reading tablespace information from the .ibd files...
InnoDB: Restoring possible half-written data pages from the doublewrite
InnoDB: buffer...
091111 9:23:38 InnoDB: Started; log sequence number 0 176116635
091111 9:23:38 [Note] Event Scheduler: Loaded 0 events
091111 9:23:38 [Note] /usr/mysql/5.1/bin/mysqld: ready for connections.
Version: '5.1.30' socket: '/tmp/mysql.sock' port: 3306 Source distribution

[cudgel]

I've now tried the following versions of Squeezecenter and get the same mysql crash whenever trying to play music or add to a playlist:

7.3.3
7.3.4-28402
7.4.2-29203
7.5.0-29225

The scan completes on all versions. The queries seem to be the problem - not DBD::mysql - the same query crashes when run directly against the database (this is against the tables 7.3.4 created):

[root @ oxygen][~]: mysql -u squeezecenter -p
Enter password:
Welcome to the MySQL monitor. Commands end with ; or \g.
Your MySQL connection id is 3
Server version: 5.1.30-log Source distribution

Type 'help;' or '\h' for help. Type '\c' to clear the buffer.

mysql> use squeezecenter;
Database changed
mysql> SELECT me.id, me.url, me.content_type, me.title, me.titlesort, me.titlesearch, me.album, me.tracknum, me.timestamp, me.filesize, me.disc, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignment, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.musicbrainz_id, me.lossless, me.lyrics, me.replay_gain, me.replay_peak, me.extid FROM tracks me JOIN albums album ON ( album.id = me.album ) LEFT JOIN contributor_track contributorTracks ON ( contributorTracks.track = me.id ) JOIN contributors contributor ON ( contributor.id = contributorTracks.contributor ) WHERE ( audio = '1' AND contributor.id = '1' AND remote = '0' ) GROUP BY me.id ORDER BY concat(album.titlesort, '0'), me.disc, me.tracknum, concat(me.titlesort, '0');
ERROR 2013 (HY000): Lost connection to MySQL server during query
mysql>

[Blue_Atom]

If you can't do a manual lookup either, this looks like it might be a mysql issue. Is it possible remove and reinstall mysql and rebuild the database?

[skarppi]

I also had similar problems with MySql server crashing on my Opensolaris box. The solution was to downgrade MySql from 5.1 series back to 5.0.81.

[Blue_Atom]

I'm running this version of mysql and it works fine;

PKGINST: CSWmysql5
NAME: mysql5 - MySQL 5 database management system - server files
CATEGORY: application
ARCH: i386
VERSION: 5.0.51,REV=2008.01.20
BASEDIR: /
VENDOR: http://www.mysql.com/ packaged for CSW by Alex Moore
PSTAMP: thor20080120121202
INSTDATE: Aug 30 2008 11:36
HOTLINE: http://www.blastwave.org/bugtrack/
EMAIL: asmoore@blastwave.org
STATUS: completely installed
FILES: 150 installed pathnames
6 shared pathnames
1 linked files
39 directories
37 executables
77598 blocks used (approx)