Bug #3828 The rollback test and others on OpenServer 5.0.7 are taking exessive time.
Submitted: 19 May 2004 3:38 Modified: 16 Sep 2005 16:56
Reporter: Boyd GERBER Email Updates:
Status: Won't fix Impact on me:
None 
Category:MySQL Server: Compiling Severity:S2 (Serious)
Version:mysql-4.0.2X/mysql-4.1.X OS:OpenServer 5.0.7
Assigned to: CPU Architecture:Any

[19 May 2004 3:38] Boyd GERBER
Description:
The "make test" all runs normally except rollback which is taking 4-8 hours and the rest of the test now take about 18-24 hours without debug for all tests to pass where in the past it was about 2-3 hours if I remember correctly.  It may have been less.  This just seems like a extremely long time now.  I am running with-debug now to see about how much longer this will make the tests run.

How to repeat:
I build this mysql-4.0.20 with

CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-innodb --with-vio --with-extra-charsets=complex
or
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-berkeley-db=./bdb --with-innodb --with-vio --with-extra-charsets=complex 
or
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-innodb --with-vio --with-extra-charsets=complex --with-debug
and
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-berkeley-db=./bdb --with-innodb --with-vio --with-extra-charsets=complex --with-debug

Which is what I have been testing the bk with.  The last bk I tried was last Friday with a pull thursday.  Most tests passed.

I have tried the following.

PATH=/usr/bin:/bin:/usr/ccs/bin:/home/gerberb/bin:.:usr/gnu/bin:/bin:/usr/bin:/usr/bin/X11:/usr/local/bin:/usr/ccs/bin:/usr/local/ubin:/usr/zenez/bin:/etc:
LIBPATH=/usr/lib:/lib:/usr/gnu/lib:/usr/local/lib:/usr/ccs/lib:
LD_LIBRARY_PATH=;/usr/lib:/lib:/usr/local/lib:/usr/local/mysql/lib/mysql:/usr/l
ocal/bdb/lib:/usr/local/ssl/lib:
or
LD_LIBRARY_PATH=/usr/lib:/lib:/usr/gnu/lib:/usr/local/lib:/usr/ccs/lib:/usr/local/mysql/lib/mysql:/usr/local/bdb/lib:/usr/lib/ssl/openssl/lib:
or
LD_LIBRARY_PATH=.:/usr/lib:/lib:/usr/gnu/lib:/usr/local/lib:/usr/ccs/lib:/usr/local/mysql/lib/mysql:/usr/local/bdb/lib:/usr/lib/ssl/openssl/lib:
and
LD_LIBRARY_PATH=/home/mount/uw713/zenez1/build/uw713/mysql-4.0.20/libmysql_r/.libs:/home/mount/uw713/zenez1/build/uw713/mysql-4.0.20/libmysql/.libs/:/usr/lib:/lib:/usr/gnu/lib:/usr/local/lib:/usr/ccs/lib:/usr/local/mysql/lib/mysql:/usr/local/bdb/lib:/usr/lib/ssl/openssl/lib:

After changinge LD_LIBRARY_PATH with each of the above I redid a configure (using the above), make and make test all giving me times from 4-20 hours with debug.  This test goes in minutes on UnixWare 7.1.3 once it is working.  Both machines are identical The have intel 2.2 MHz processors.  UnixWare reports 1994MHz omstead pf 1896MHz on OpenServer.  The hw info is below.

Report about cpu for osr507.zenez.com on Wed May 19 01:08:34 2004

    There is one CPU on this system.

    The CPU performs like a 1896Mhz Intel Pentium 4

        Processor:     1 (0xffffe)
        Vendor ID:     GenuineIntel
        cpu_family:    15
        cpu_id:        0x0a000f29
            type:        0
            family:      15
            model:       2
            stepping:    9
            brandId:     10

Report about memory for osr507.zenez.com on Wed May 19 01:08:34 2004

    BootFlags:  AT
                Intel 80486
                PCI
                APM

    BaseMem:        639 Kb
    ExtMem:      479.19 Mb
    BootStr:    hd(40)unix
    Kernel:     /unix -> /stand/unix

                        Available Memory

    0x00000000-0x00007fff       32 Kb   Kernel reserved
    0x00008000-0x0009efff      604 Kb
    0x0009f000-0x000fffff      388 Kb   Non-RAM
    0x00100000-0x00d3cfff    12.24 Mb
    0x00d3d000-0x00ffffff     2.76 Mb   Kernel data
    0x01000000-0x1ed04fff   477.02 Mb   Shadow ram & other special mem
                                        Non-ISA-DMAable
    0x1ed05000-0x1ef2ffff     2.17 Mb   Kernel text
                                        Shadow ram & other special mem
                                        Non-ISA-DMAable
                            ---------
                            494.81 Mb   RAM total  (495 Mb - 196 Kb)

                        Memory used by Kernel

    0x00000000-0x00007fff       32 Kb   Kernel reserved
    0x00008000-0x0009fbff      607 Kb   Unused
    0x0009fc00-0x0009ffff        1 Kb   BIOS private area - Reserved
    0x000a0000-0x000e5fff      280 Kb   Unused
    0x000e6000-0x000fffff      104 Kb   BIOS private area - Reserved
    0x00100000-0x00d3cfff    12.24 Mb   Unused
    0x00d3d000-0x00ffffff     2.76 Mb   Kernel data
    0x01000000-0x1ed04fff   477.02 Mb   Unused
    0x1ed05000-0x1ef2ffff     2.17 Mb   Kernel text
                                        Shadow ram & other special mem
                                        Non-ISA-DMAable
    0x1ef30000-0x1effffff      832 Kb   BIOS private area - Reserved
    0x1f000000-0xfeceffff   3580.94 Mb  Unused
    0xfecf0000-0xfecf0fff        4 Kb   BIOS private area - Reserved
    0xfecf1000-0xfed1ffff      188 Kb   Unused
    0xfed20000-0xfed9ffff      512 Kb   BIOS private area - Reserved
                            ---------
                              6.38 Mb   RAM total  (7 Mb - 635 Kb)

Report about MP for osr507.zenez.com on Wed May 19 01:08:34 2004

    There is one CPU on this system.

    Your system is using the `pcmp' GPI
        MP Vendor:    Intel Multiprocessor Specification
        Vendor class: Intel Multiprocessor Specification
        MP Version:   mpsw version 3

    Intel MPS driver data:

        Floating Structure
            signature:      "_MP_"
            config_addr:    0x000fc590
            length:         1
            spec_rev:       4     supported
            checksum:       0x3c
            feature1:       0x00  Configuration structure present
            feature2:       0x00  Virtual Wire Mode
                                  Shared Processor Clock Source
            feature3:       0x00
            feature4:       0x00
            feature5:       0x00

        Header Structure
            signature:      "PCMP"
            length:         0x140
            spec_rev:       0x4
            checksum:       0xa
            oemid:          "^@^@^@^@^@^@^@^@"
            productid       "Springdale-G"
            oem_addr:       0x0
            oem_size:       0x0
            entry_count:    0x21
            local_addr:     0xfee00000

        Processor Entry
            local_id:       0x0
            local_version:  0x14
            usable:         1
            bootstrap:      1
            family:         15
            model:          2
            stepping:       9
            features:       0xbfebfbff

        Bus Entry
            bus_id:         0x0
            bus:            "PCI   "

        Bus Entry
            bus_id:         0x1
            bus:            "PCI   "

        Bus Entry
            bus_id:         0x2
            bus:            "ISA   "

        I/O Apic Entry
            io_id:          0x1
            io_version:     0x20
            usable:         1
            io_addr:        0xfec00000

        Interrupt Entry
            intr_type:      0x03  External Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x00
            apic_id:        0x01
            apic_pin:       0x00

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x01
            apic_id:        0x01
            apic_pin:       0x01

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x00
            apic_id:        0x01
            apic_pin:       0x02

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x03
            apic_id:        0x01
            apic_pin:       0x03

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x04
            apic_id:        0x01
            apic_pin:       0x04

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x05
            apic_id:        0x01
            apic_pin:       0x05

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x06
            apic_id:        0x01
            apic_pin:       0x06

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x07
            apic_id:        0x01
            apic_pin:       0x07

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x01  Active High
            trigger:        0x01  Edge
            source_bus:     0x02  ISA
            source_irq:     0x08
            apic_id:        0x01
            apic_pin:       0x08

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x01  Active High
            trigger:        0x03  Level
            source_bus:     0x02  ISA
            source_irq:     0x09
            apic_id:        0x01
            apic_pin:       0x09

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0a
            apic_id:        0x01
            apic_pin:       0x0a

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0b
            apic_id:        0x01
            apic_pin:       0x0b

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0c
            apic_id:        0x01
            apic_pin:       0x0c

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0d
            apic_id:        0x01
            apic_pin:       0x0d

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0e
            apic_id:        0x01
            apic_pin:       0x0e

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x02  ISA
            source_irq:     0x0f
            apic_id:        0x01
            apic_pin:       0x0f

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x08  PCI INT_A#
                                  PCI DeviceNum 2
            apic_id:        0x01
            apic_pin:       0x10

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x01  PCI
            source_irq:     0x20  PCI INT_A#
                                  PCI DeviceNum 8
            apic_id:        0x01
            apic_pin:       0x14

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x7c  PCI INT_A#
                                  PCI DeviceNum 31
            apic_id:        0x01
            apic_pin:       0x12

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x7d  PCI INT_B#
                                  PCI DeviceNum 31
            apic_id:        0x01
            apic_pin:       0x11

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x74  PCI INT_A#
                                  PCI DeviceNum 29
            apic_id:        0x01
            apic_pin:       0x10

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x75  PCI INT_B#
                                  PCI DeviceNum 29
            apic_id:        0x01
            apic_pin:       0x13

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x76  PCI INT_C#
                                  PCI DeviceNum 29
            apic_id:        0x01
            apic_pin:       0x12

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x00  PCI
            source_irq:     0x77  PCI INT_D#
                                  PCI DeviceNum 29
            apic_id:        0x01
            apic_pin:       0x17

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x01  PCI
            source_irq:     0x04  PCI INT_A#
                                  PCI DeviceNum 1
            apic_id:        0x01
            apic_pin:       0x16

        Interrupt Entry
            intr_type:      0x00  APIC Vectored
            polarity:       0x03  Active Low
            trigger:        0x03  Level
            source_bus:     0x01  PCI
            source_irq:     0x08  PCI INT_A#
                                  PCI DeviceNum 2
            apic_id:        0x01
            apic_pin:       0x11

        Local Interrupt
            intr_type:      0x03  External Vectored
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x00  PCI
            source_irq:     0x00  PCI INT_A#
                                  PCI DeviceNum 0
            apic_id:        0xff
            apic_pin:       0x00

        Local Interrupt
            intr_type:      0x01  NMI
            polarity:       0x00  Bus Default
            trigger:        0x00  Bus Default
            source_bus:     0x00  PCI
            source_irq:     0x00  PCI INT_A#
                                  PCI DeviceNum 0
            apic_id:        0xff
            apic_pin:       0x01

    Local APIC 0
        APIC ID:           0x00000000
        APIC Version:      0x00050014
        Task Priority:     0x00000008
        Arb Priority:      0x00000000
        Proc Priority:     0x00000000
        Remote Read:       0x00000000
        Logical Dest:      0x01000000
        Dest Format:       0xffffffff
        Spurious Int Vect: 0x0000016f
        In-Service:        0x00000000000000000000000000000000
                           0x00000000000000000000000000000000
        Interrupt Req:     0x00000000000000000000000000000000
                           0x00000000000000000000000000000000
        Trigger Mode:      0x00000000000000000000000000000000
                           0x00000000000000180000000000000000
        Error Status:      0x00000000
        Interrupt Command: 0x0e00000000000630
        Timer:             0x00020050
        Lint0:             0x00010000
        Lint1:             0x00008400
        Error:             0x00010000
        Initial Count:     0x000f3884
        Current Count:     0x000af7ce
        Timer Divide Conf: 0x0000000b

$(configure from above)
$ make
$ make test
cd mysql-test ; ./mysql-test-run
Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/master-data --skip-innodb --skip-bdb --skip-warnings --language=../sql/share/english/
040519  3:04:44  ../sql/mysqld: Shutdown Complete

Installing Slave Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/slave-data --skip-innodb --skip-bdb --skip-warnings --language=../sql/share/english/
040519  3:04:44  ../sql/mysqld: Shutdown Complete

Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

 TEST                           RESULT
------------------------------------------
alias                          [ pass ]
alter_table                    [ pass ]
analyse                        [ pass ]
ansi                           [ pass ]
auto_increment                 [ pass ]
backup                         [ pass ]
bdb-alter-table-1              [ pass ]
bdb-alter-table-2              [ pass ]
bdb-crash                      [ pass ]
bdb-deadlock                   [ pass ]
bdb                            [ pass ]
bdb_cache                      [ pass ]
bench_count_distinct           [ pass ]
bigint                         [ pass ]
binary                         [ pass ]
bool                           [ pass ]
bulk_replace                   [ pass ]
case                           [ pass ]
cast                           [ pass ]
check                          [ pass ]
comments                       [ pass ]
compare                        [ pass ]
constraints                    [ pass ]
convert                        [ pass ]
count_distinct                 [ pass ]
count_distinct2                [ pass ]
create                         [ pass ]
ctype_cp1251                   [ pass ]
ctype_latin1_de                [ pass ]
ctype_tis620                   [ pass ]
ctype_ujis                     [ pass ]
delayed                        [ pass ]
delete                         [ pass ]
dirty_close                    [ pass ]
distinct                       [ pass ]
drop                           [ pass ]
drop_temp_table                [ pass ]
empty_table                    [ pass ]
errors                         [ pass ]
explain                        [ pass ]
flush                          [ pass ]
flush_table                    [ pass ]
foreign_key                    [ pass ]
fulltext                       [ pass ]
fulltext_cache                 [ pass ]
fulltext_distinct              [ pass ]
fulltext_left_join             [ pass ]
fulltext_multi                 [ pass ]
fulltext_order_by              [ pass ]
fulltext_update                [ pass ]
fulltext_var                   [ pass ]
func_concat                    [ pass ]
func_crypt                     [ pass ]
func_date_add                  [ pass ]
func_encrypt                   [ skipped ]
func_equal                     [ pass ]
func_group                     [ pass ]
func_if                        [ pass ]
func_in                        [ pass ]
func_isnull                    [ pass ]
func_like                      [ pass ]
func_math                      [ pass ]
func_misc                      [ pass ]
func_op                        [ pass ]
func_regexp                    [ pass ]
func_set                       [ pass ]
func_str                       [ pass ]
func_system                    [ pass ]
func_test                      [ pass ]
func_time                      [ pass ]
func_timestamp                 [ pass ]
gcc296                         [ pass ]
grant                          [ pass ]
grant_cache                    [ pass ]
group_by                       [ pass ]
handler                        [ pass ]
having                         [ pass ]
heap                           [ pass ]
init_file                      [ pass ]
innodb-deadlock                [ pass ]
innodb-lock                    [ pass ]
innodb                         [ pass ]
innodb_cache                   [ pass ]
innodb_handler                 [ pass ]
insert                         [ pass ]
insert_select                  [ pass ]
isam                           [ pass ]
join                           [ pass ]
join_crash                     [ pass ]
join_outer                     [ pass ]
key                            [ pass ]
key_diff                       [ pass ]
key_primary                    [ pass ]
keywords                       [ pass ]
kill                           [ pass ]
limit                          [ pass ]
loaddata                       [ pass ]
lock                           [ pass ]
lock_multi                     [ pass ]
lock_tables_lost_commit        [ pass ]
lowercase_table                [ pass ]
lowercase_table2               [ skipped ]
lowercase_table3               [ skipped ]
merge                          [ pass ]
mix_innodb_myisam_binlog       [ pass ]
multi_update                   [ pass ]
myisam-blob                    [ pass ]
myisam                         [ pass ]
mysqlbinlog                    [ pass ]
mysqldump                      [ pass ]
null                           [ pass ]
null_key                       [ pass ]
odbc                           [ pass ]
olap                           [ pass ]
openssl_1                      [ skipped ]
order_by                       [ pass ]
order_fill_sortbuf             [ pass ]
outfile                        [ pass ]
overflow                       [ pass ]
packet                         [ pass ]
query_cache                    [ pass ]
query_cache_merge              [ pass ]
raid                           [ skipped ]
range                          [ pass ]
rename                         [ pass ]
repair                         [ pass ]
repair_part2                   [ pass ]
replace                        [ pass ]
rollback                       [ pass ]

It now has been waiting at this stage for 45 minutes with out displaying any thing.  It took 3.5 hours to put the pass in rollback.  I will update this once all the tests either finish or fail with the total time..

The test with out debug took 18 hours, but has taken up to 24 hours.  This one is now running with debug to see how much longer it takes.  To get this far with the rollback test took 4 hours till it printed out the pass.

Suggested fix:
unknown.
[19 May 2004 3:49] Boyd GERBER
This is also the reason for the sudden large expansion of /tmp/sh... files I have noticed from the build scripts.  Maybe this never finishes and that is why the sh files are never removed.  This started happening last Thursday or Friday.  I let Lenz know and since Lenz is on vacation I let Patrick Galbraith know about the files.  I did not relate it to this issue when talking with either of them.  The files start growing with the rollback and then nothing is printed to my display after the rollback finally puts a pass.  It has now been almost 2 hours since any output to the display after the rollback finally put a pass.  This does not seem to be working correctly.
[19 May 2004 12:22] Boyd GERBER
After 5 more hours I got

rpl000001                      [ fail ]

Errors are (from /home/osr5/mysql-4.0.20/mysql-test/var/log/mysqltest-time) :
/home/osr5/mysql-4.0.20/client/.libs/mysqltest: At line 85: query 'select (@id := id) - id from t2' failed: 2013: Lost connection to MySQL server during query
(the last line(s) may be the ones that caused the die() in mysqltest)

Aborting. To continue, re-run with '--force'.

Ending Tests
Shutting-down MySQL daemon

master not cooperating with mysqladmin, will try manual kill
master responded to SIGTERM
Master shutdown finished
Slave shutdown finished
make: *** [test] Error 1

leaving 470 sh... files in /tmp/

---------------------------master.err------------------------------------------
CURRENT_TEST: alias
040519  3:04:44  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
InnoDB: The first specified data file ./ibdata1 did not exist:
InnoDB: a new database to be created!
040519  3:04:44  InnoDB: Setting file ./ibdata1 size to 50 MB
InnoDB: Database physically writes the file full: wait...
040519  3:04:47  InnoDB: Log file ./ib_logfile0 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile0 size to 5 MB
InnoDB: Database physically writes the file full: wait...
040519  3:04:47  InnoDB: Log file ./ib_logfile1 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile1 size to 5 MB
InnoDB: Database physically writes the file full: wait...
InnoDB: Doublewrite buffer not found: creating new
InnoDB: Doublewrite buffer created
InnoDB: Creating foreign key constraint system tables
InnoDB: Foreign key constraint system tables created
040519  3:04:48  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:04:50  Aborted connection 7 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:04:50  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:04:50  InnoDB: Starting shutdown...
040519  3:04:52  InnoDB: Shutdown completed
040519  3:04:52  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.32, System time 0.70
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: ansi
040519  3:04:54  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:04:54  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:04:56  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:04:56  InnoDB: Starting shutdown...
040519  3:04:59  InnoDB: Shutdown completed
040519  3:04:59  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.13, System time 0.05
Maximum resident set size 138428864, Integral resident set size 134509452
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509468
Blocks in 134778585 out -2146873971, Messages in 0 out 134509468, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: auto_increment
040519  3:05:01  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:01  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:03  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:03  InnoDB: Starting shutdown...
040519  3:05:05  InnoDB: Shutdown completed
040519  3:05:05  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.11, System time 0.10
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: backup
040519  3:05:07  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:07  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:09  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:09  InnoDB: Starting shutdown...
040519  3:05:12  InnoDB: Shutdown completed
040519  3:05:12  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.14, System time 0.08
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: bdb-alter-table-2
040519  3:05:14  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:14  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:16  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:16  InnoDB: Starting shutdown...
040519  3:05:18  InnoDB: Shutdown completed
040519  3:05:18  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.13, System time 0.05
Maximum resident set size 138428864, Integral resident set size 134509432
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509448
Blocks in 134778585 out -2146873971, Messages in 0 out 134509448, Signals 134778
601

Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: bdb-crash
040519  3:05:20  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:20  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:22  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:22  InnoDB: Starting shutdown...
040519  3:05:25  InnoDB: Shutdown completed
040519  3:05:25  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.13, System time 0.05
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: bdb_cache
040519  3:05:26  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:27  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:29  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:29  InnoDB: Starting shutdown...
040519  3:05:31  InnoDB: Shutdown completed
040519  3:05:31  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.09, System time 0.08
Maximum resident set size 138428864, Integral resident set size 134509424
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509440
Blocks in 134778585 out -2146873971, Messages in 0 out 134509440, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: bench_count_distinct
040519  3:05:33  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:33  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:42  Aborted connection 16 to db: 'unconnected' user: 'root' host: `
localhost' (Got an error writing communication packets)
040519  3:05:42  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:42  InnoDB: Starting shutdown...
040519  3:05:43  InnoDB: Shutdown completed
040519  3:05:43  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 2.65, System time 2.17
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: count_distinct2
040519  3:05:45  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:46  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:53  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:05:53  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:53  InnoDB: Starting shutdown...
040519  3:05:54  InnoDB: Shutdown completed
040519  3:05:54  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 1.93, System time 1.71
Maximum resident set size 138428864, Integral resident set size 134509416
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509432
Blocks in 134778585 out -2146873971, Messages in 0 out 134509432, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: create
040519  3:05:56  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:05:56  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:05:58  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:05:58  InnoDB: Starting shutdown...
040519  3:06:02  InnoDB: Shutdown completed
040519  3:06:02  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.16, System time 0.10
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: ctype_cp1251
040519  3:06:04  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:05  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:07  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:07  InnoDB: Starting shutdown...
040519  3:06:09  InnoDB: Shutdown completed
040519  3:06:09  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.12, System time 0.06
Maximum resident set size 138428864, Integral resident set size 134509416
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509432
Blocks in 134778585 out -2146873971, Messages in 0 out 134509432, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: ctype_latin1_de
040519  3:06:11  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:11  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:13  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:13  InnoDB: Starting shutdown...
040519  3:06:15  InnoDB: Shutdown completed
040519  3:06:15  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.11, System time 0.06
Maximum resident set size 138428864, Integral resident set size 134509412
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509428
Blocks in 134778585 out -2146873971, Messages in 0 out 134509428, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: ctype_tis620
040519  3:06:17  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:17  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:19  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:19  InnoDB: Starting shutdown...
040519  3:06:22  InnoDB: Shutdown completed
040519  3:06:22  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.12, System time 0.05
Maximum resident set size 138428864, Integral resident set size 134509428
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509444
Blocks in 134778585 out -2146873971, Messages in 0 out 134509444, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: ctype_ujis
040519  3:06:24  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:24  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:26  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:26  InnoDB: Starting shutdown...
040519  3:06:28  InnoDB: Shutdown completed
040519  3:06:28  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.12, System time 0.05
Maximum resident set size 138428864, Integral resident set size 134509428
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509444
Blocks in 134778585 out -2146873971, Messages in 0 out 134509444, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: delayed
040519  3:06:30  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:30  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:35  Aborted connection 6 to db: 'test' user: 'root' host: `localhos
t' (Got an error reading communication packets)
040519  3:06:36  Aborted connection 17 to db: 'test' user: 'root' host: `localho
st' (Got an error reading communication packets)
040519  3:06:38  Aborted connection 32 to db: 'unconnected' user: 'root' host: `
localhost' (Got an error writing communication packets)
040519  3:06:38  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:38  InnoDB: Starting shutdown...
040519  3:06:40  InnoDB: Shutdown completed
040519  3:06:41  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.38, System time 0.36
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: func_encrypt
/home/osr5/mysql-4.0.20/sql/mysqld: WARNING: unknown variable 'des-key-file=/hom
e/osr5/mysql-4.0.20/mysql-test/std_data/des_key_file'
040519  3:06:42  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:42  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:44  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:44  InnoDB: Starting shutdown...
040519  3:06:46  InnoDB: Shutdown completed
040519  3:06:46  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.11, System time 0.06
Maximum resident set size 138428864, Integral resident set size 134509380
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509396
Blocks in 134778585 out -2146873971, Messages in 0 out 134509396, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: func_equal
040519  3:06:48  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:48  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:52  Aborted connection 19 to db: 'unconnected' user: 'root' host: `
localhost' (Got an error writing communication packets)
040519  3:06:52  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:52  InnoDB: Starting shutdown...
040519  3:06:55  InnoDB: Shutdown completed
040519  3:06:55  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.41, System time 0.28
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: grant_cache
040519  3:06:56  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:06:57  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:06:59  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:06:59  InnoDB: Starting shutdown...
040519  3:07:01  InnoDB: Shutdown completed
040519  3:07:01  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.16, System time 0.08
Maximum resident set size 138428864, Integral resident set size 134509416
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509432
Blocks in 134778585 out -2146873971, Messages in 0 out 134509432, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: group_by
040519  3:07:03  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:07:03  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:07:06  Aborted connection 5 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:07:06  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:07:06  InnoDB: Starting shutdown...
040519  3:07:08  InnoDB: Shutdown completed
040519  3:07:08  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.27, System time 0.30
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: init_file
040519  3:07:09  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:07:09  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:07:38  Aborted connection 3 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:07:38  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:07:38  InnoDB: Starting shutdown...
040519  3:07:40  InnoDB: Shutdown completed
040519  3:07:40  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 28.84, System time 0.03
Maximum resident set size 138428864, Integral resident set size 134509388
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509404
Blocks in 134778585 out -2146873971, Messages in 0 out 134509404, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: innodb-deadlock
040519  3:07:41  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:07:41  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:07:55  Aborted connection 8 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:07:55  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:07:55  InnoDB: Starting shutdown...
040519  3:07:58  InnoDB: Shutdown completed
040519  3:07:58  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 5.21, System time 1.08
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: innodb_cache
040519  3:07:59  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:07:59  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:01  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:08:01  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:01  InnoDB: Starting shutdown...
040519  3:08:06  InnoDB: Shutdown completed
040519  3:08:06  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.40, System time 0.12
Maximum resident set size 138428864, Integral resident set size 134509424
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509440
Blocks in 134778585 out -2146873971, Messages in 0 out 134509440, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: innodb_handler
040519  3:08:08  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:08  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:27  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:27  InnoDB: Starting shutdown...
040519  3:08:28  InnoDB: Shutdown completed
040519  3:08:28  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.82, System time 1.67
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: lock_tables_lost_commit
040519  3:08:30  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:30  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:32  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:32  InnoDB: Starting shutdown...
040519  3:08:37  InnoDB: Shutdown completed
040519  3:08:37  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.13, System time 0.07
Maximum resident set size 138428864, Integral resident set size 134509432
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509448
Blocks in 134778585 out -2146873971, Messages in 0 out 134509448, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: lowercase_table
040519  3:08:38  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:39  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:41  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:41  InnoDB: Starting shutdown...
040519  3:08:43  InnoDB: Shutdown completed
040519  3:08:43  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.12, System time 0.09
Maximum resident set size 138428864, Integral resident set size 134509432
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509448
Blocks in 134778585 out -2146873971, Messages in 0 out 134509448, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: lowercase_table2
040519  3:08:45  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:45  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:47  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:47  InnoDB: Starting shutdown...
040519  3:08:49  InnoDB: Shutdown completed
040519  3:08:49  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.11, System time 0.06
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: lowercase_table3
040519  3:08:51  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:51  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:08:53  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:08:53  InnoDB: Starting shutdown...
040519  3:08:56  InnoDB: Shutdown completed
040519  3:08:56  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.09, System time 0.11
Maximum resident set size 138428864, Integral resident set size 134509432
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509448
Blocks in 134778585 out -2146873971, Messages in 0 out 134509448, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: merge
040519  3:08:58  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:08:58  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:01  Aborted connection 5 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:09:01  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:01  InnoDB: Starting shutdown...
040519  3:09:04  InnoDB: Shutdown completed
040519  3:09:04  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.21, System time 0.41
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: multi_update
040519  3:09:06  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:06  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:15  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:09:15  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:15  InnoDB: Starting shutdown...
040519  3:09:16  InnoDB: Shutdown completed
040519  3:09:17  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 3.14, System time 2.74
Maximum resident set size 138428864, Integral resident set size 134509424
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509440
Blocks in 134778585 out -2146873971, Messages in 0 out 134509440, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: myisam-blob
040519  3:09:18  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:24  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:09:24  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:24  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 2.66, System time 0.58
Maximum resident set size 138428864, Integral resident set size 134509388
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509404
Blocks in 134778585 out -2146873971, Messages in 0 out 134509404, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: myisam
040519  3:09:27  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:27  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:31  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:09:31  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:31  InnoDB: Starting shutdown...
040519  3:09:33  InnoDB: Shutdown completed
040519  3:09:33  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.92, System time 0.61
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: mysqlbinlog
040519  3:09:35  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:35  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:37  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:37  InnoDB: Starting shutdown...
040519  3:09:39  InnoDB: Shutdown completed
040519  3:09:39  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.14, System time 0.10
Maximum resident set size 138428864, Integral resident set size 134509436
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509452
Blocks in 134778585 out -2146873971, Messages in 0 out 134509452, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: mysqldump
040519  3:09:41  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:42  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:45  Aborted connection 12 to db: 'unconnected' user: 'root' host: `
localhost' (Got an error writing communication packets)
040519  3:09:45  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:45  InnoDB: Starting shutdown...
040519  3:09:48  InnoDB: Shutdown completed
040519  3:09:48  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.42, System time 0.70
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: order_fill_sortbuf
040519  3:09:50  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:50  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:09:54  Aborted connection 2 to db: 'unconnected' user: 'root' host: `l
ocalhost' (Got an error writing communication packets)
040519  3:09:54  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:09:54  InnoDB: Starting shutdown...
040519  3:09:56  InnoDB: Shutdown completed
040519  3:09:56  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 0.80, System time 0.67
Maximum resident set size 138428864, Integral resident set size 134509428
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509444
Blocks in 134778585 out -2146873971, Messages in 0 out 134509444, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: outfile
040519  3:09:58  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:09:58  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:10:19  Note: Found 1 of 0 rows when repairing './test/t1'
040519  3:10:20  Aborted connection 14 to db: 'unconnected' user: 'root' host: `
localhost' (Got an error writing communication packets)
040519  3:10:20  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519  3:10:20  InnoDB: Starting shutdown...
040519  3:10:23  InnoDB: Shutdown completed
040519  3:10:23  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 2.76, System time 2.18
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118
CURRENT_TEST: repair_part2
040519  3:10:24  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
040519  3:10:24  InnoDB: Started
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/master.sock'  port: 9306
040519  3:10:26  Note: Found 1 of 0 rows when repairing './test/t1'
040519 11:10:29  Aborted connection 4 to db: 'test' user: 'root' host: `localhos
t' (Got timeout reading communication packets)
040519 11:10:41  Aborted connection 6 to db: 'test' user: 'root' host: `localhos
t' (Got timeout reading communication packets)
040519 11:10:47  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519 11:10:49  InnoDB: Starting shutdown...
040519 11:10:53  InnoDB: Shutdown completed
040519 11:10:53  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 11121.69, System time 17639.14
Maximum resident set size 138428864, Integral resident set size 134509460
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509476
Blocks in 134778585 out -2146873971, Messages in 0 out 134509476, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118

---------------------------slave.err------------------------------------------
....Normail output.......
CURRENT_TEST: rollback
CURRENT_TEST: rpl000001
040519  3:10:26  Warning: setrlimit could not change the size of core files to '
infinity';  We may not be able to generate a core file on signals
/home/osr5/mysql-4.0.20/sql/mysqld: ready for connections.
Version: '4.0.20-debug-log'  socket: '/home/osr5/mysql-4.0.20/mysql-test/var/tmp
/slave.sock'  port: 9307
040519  3:10:28  Slave I/O thread: connected to master 'root@127.0.0.1:9306',  r
eplication started in log 'FIRST' at position 4
040519  3:10:28  Slave SQL thread initialized, starting replication in log 'FIRS
T' at position 0, relay log '/home/osr5/mysql-4.0.20/mysql-test/var/log/slave-re
lay-bin.001' position: 4
040519  3:10:29  Slave I/O thread killed while reading event
040519  3:10:29  Slave I/O thread exiting, read up to log 'master-bin.001', posi
tion 1569
040519  3:10:29  Error reading relay log event: slave SQL thread was killed
040519  3:10:29  Slave SQL thread initialized, starting replication in log 'mast
er-bin.001' at position 1569, relay log '/home/osr5/mysql-4.0.20/mysql-test/var/
log/slave-relay-bin.002' position: 1610
040519  3:10:29  Slave I/O thread: error connecting to master 'root@127.0.0.1:93
06': Error: 'Access denied for user: 'root@localhost' (Using password: NO)'  err
no: 1045  retry-time: 1  retries: 10
040519  3:10:31  Slave I/O thread: connected to master 'root@127.0.0.1:9306',  r
eplication started in log 'master-bin.001' at position 1569
040519  3:10:33  Slave I/O thread killed while reading event
040519  3:10:33  Slave I/O thread exiting, read up to log 'master-bin.001', posi
tion 1902
040519  3:10:33  Error reading relay log event: slave SQL thread was killed
040519  3:10:36  Slave SQL thread initialized, starting replication in log 'FIRS
T' at position 0, relay log '/home/osr5/mysql-4.0.20/mysql-test/var/log/slave-re
lay-bin.001' position: 4
040519  3:10:36  Slave I/O thread: connected to master 'root@127.0.0.1:9306',  r
eplication started in log 'FIRST' at position 4
040519  3:11:01  Slave I/O thread: error reconnecting to master 'root@127.0.0.1:
9306': Error: ''  errno: 1159  retry-time: 1  retries: 10
040519  3:12:40  Slave I/O thread killed during or after a reconnect done to rec
over from failed read
040519  3:12:40  Slave I/O thread exiting, read up to log 'master-bin.001', posi
tion 769321
040519 11:10:29  Aborted connection 2 to db: 'test' user: 'root' host: `localhos
t' (Got timeout reading communication packets)
040519 11:10:41  Aborted connection 1 to db: 'test' user: 'root' host: `localhos
t' (Got timeout reading communication packets)
040519 11:10:53  /home/osr5/mysql-4.0.20/sql/mysqld: Normal shutdown

040519 11:10:53  Error reading relay log event: slave SQL thread was killed
040519 11:10:53  /home/osr5/mysql-4.0.20/sql/mysqld: Shutdown Complete

User time 1.98, System time 1.71
Maximum resident set size 138428864, Integral resident set size 134509116
Non-physical pagefaults -2146873971, Physical pagefaults 0, Swaps 134509132
Blocks in 134778585 out -2146873971, Messages in 0 out 134509132, Signals 134778
601
Voluntary context switches 0, Involuntary context switches 137047118

-------------------/home/osr5/mysql-4.0.20/mysql-test/var/log------------------
$ ls -la
total 12062
-rw-r--r--   1 gerberb  zenez         10 May 18 18:10 current_test
-rw-r--r--   1 gerberb  zenez      12500 May 19 02:10 manager.log
-rw-rw----   1 gerberb  zenez     769564 May 19 02:10 master-bin.001
-rw-rw----   1 gerberb  zenez         58 May 18 18:10 master-bin.index
-rw-r--r--   1 gerberb  zenez      37449 May 19 02:10 master.err
-rw-rw----   1 gerberb  zenez    3116689 May 18 18:10 master.log
-rw-r--r--   1 gerberb  zenez        159 May 19 02:10 mysqltest-time
-rw-rw----   1 gerberb  zenez     771261 May 19 02:10 slave-bin.001
-rw-rw----   1 gerberb  zenez         57 May 18 18:10 slave-bin.index
-rw-rw----   1 gerberb  zenez     769362 May 18 18:10 slave-relay-bin.002
-rw-rw----   1 gerberb  zenez         63 May 18 18:10 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       6253 May 19 02:10 slave.err
-rw-rw----   1 gerberb  zenez     651077 May 19 02:10 slave.log

As seen above the test started at 18:10 and ended at 02:10 which is 8 hours for a fail.
[19 May 2004 12:24] Boyd GERBER
This shows an innodb problem.  The total diskspace on partition
Disk space: 9765.74 MB of 38028.29 MB available (25.68%)
[29 May 2004 16:51] MySQL Verification Team
Boyd,

Thanks again for helping us improve MySQL on SCO platforms.

In this case we truly need more data as we do not have access to 5.0.7.

Can you please run only rollback test with profiling, so that we could see where is time spent it, like this:

./mysql-test-run --local --gprof rollback

You have to be in mysql-test directory and you should have gprof installed and mysql built for gprof.
[30 May 2004 5:26] Boyd GERBER
The latest bk does not have problems with roll back for the 4.0.X tree but does have innodb problems.  I have noticed from the interals list a lot of innodb patches and I have not had time to do a test from this bk as I spent a lot of time on my other bug report.  I have noticed that this behavior has moved to 4.1.X and I see the mysqldev has many process over 2 days.  Currently there are currently two build process running that used to take just a couple hours but now are failing like what started with mysql-4.0.20.  MySQL-4.0.19 does not have these problems.

So a merge from 4.0 to 4.1 has started doing this.  I tested the bk of 4.1 on Sunday May 16 and it did not have the problem and now on the 29 th the bk of 4.1 has the problem.  So something 13 days has caused this problem.  The bk I tested was from Thursday the 27th.  

It will be later next week before I can look into this more as my son is in the hospital recoverying from back surgery on May 28.

Sorry, I had hope to get this resolved before 4.1.2 is released, but it may not happen.

Thanks.
[31 May 2004 15:05] Heikki Tuuri
Boyd,

please build mysqld with the debug info and run it inside a debugger to determine what it is doing when it appears to be in an infinite loop.

Regards,

Heikki
[1 Jun 2004 14:26] MySQL Verification Team
Sorry,

but 4.1.2 is released.

The only way to resolve this is as I described here below.

This is because InnoDB received many changes in the source tree in the last
two weeks.
[13 Oct 2004 23:53] Boyd GERBER
This still fails on all versions of MySQL-4.0.x.  I am not sure what other information you need.
[14 Oct 2004 1:09] Boyd GERBER
The problem is that since mysql-4.0.19 the rpl000001 and all other rpl tests now fail.  They take from 8-12 hours each to fail.
[28 Oct 2004 3:16] Matthew Lord
Hi Boyd,

I'm not sure how much it would be worth it to verify this on the same machine you were
working on :-).  Did you create a tracefile with the debug binaries?  If there still on the machine
somewhere so that I could create a trace file?

Thanks!
[14 Feb 2005 22:54] Bugs System
No feedback was provided for this bug for over a month, so it is
being suspended automatically. If you are able to provide the
information that was originally requested, please do so and change
the status of the bug back to "Open".
[17 Feb 2005 18:46] Boyd GERBER
Still happens on all MySQL Versions.   Going into debugger some times allows tests to work.
[17 Feb 2005 19:53] MySQL Verification Team
Boyd,

As we do not have SCO box any more, could you try any of the two (your choice):

* run a test with debug trace file
* run it with gprof 

and tell us where is all that time wasted ??
[22 Feb 2005 1:00] Boyd GERBER
It now is worse than ever.  It appears that now all tests are doing it.  This is with MySQL-4.1.10.  I started with the below command at 17:44 and now at 18:00 the first test still has not completed....

$ /usr/local/bin/make test 

CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client --with-berkeley-db=./bdb --with-innodb --with-openssl --with-vio --with-extra-charsets=complex

The above is the command I used to configure this version of MySQL.
[22 Feb 2005 13:57] MySQL Verification Team
Hi Boyd,

I truly beleive you regarding the phenomena that you are experiencing.

But, we have built recently the latest 4.0 and 4.1 for one customer and our binary passed all the tests.

This is what we have used:

SCO OpenServer 5.0.7
gmake 3.80
gcc 2.95.3
FSU 3.5c
SCO Development 5.2.0Aa

Can you compare the above list with what you have ??

Especially compiler and FSU lib.
[22 Feb 2005 16:15] Boyd GERBER
FSU-threads-3.5c has major problems and siliently fails.  That is the main reason for the update to FSU-threads-3.14.  I have had loss of data without knowing till I did through checks.  Could you have someone run with the 3.14 threads.

ftp://ftp.zenez.com/pub/zenez/prgms/FSU-threads-3.14.tgz  

or from the main FSU-threads page.

http://moss.csc.ncsu.edu/~mueller/pthreads

I have had 5 other people try on their systems with this and the exact same results.

OpenServer 5.0.7 with all patches and updates to 
UP3 1.0.0la
MP3 1.0.0Hc
FSU-threads-3.14
MySQL-4.0.23a, MySQL-4.1.9 or MySQL-4.1.10
GNU Devtools 5.0.7Kj with gcc-2.95.3
make-3.80
gwxlibs-1.3.3Db
SCO Development 5.2.0Aa

There is  a special ld that is needed if the SCO Development System is not installed.
It currently is only available via NDA and beta testing programs.
[22 Feb 2005 16:34] MySQL Verification Team
Boyd,

sorry for misinforming you.

FSU 3.5c was a starting version, but we finished eventually with 3.14.

We did apply some changes to system header files, including the ones you have on your site, but not only those. 

Unfortunately a binary and all changes are left on customer's machine as we do not have many SCO binaries demanded. 

Of all the tests only some failed regarding floating point precision, which were not deemed crucial by the client. 

Please do note that compiler used was GCC 2.95.3.
[23 Feb 2005 20:10] Boyd GERBER
Do you know what files were changed or have patches to them?  I asked for some people to also test this problem on a NDA list and 20 people responded all with the exact same problems.  With mysql-4.1.10 it does not matter is --with-debug is used in addition to what is below for configure options.  This used to allow the tests to work.  I would like to find the cause of this an erradicate it.  What I have had these 20 people do is as follows.

1 Fresh install of OpenServer 5.0.7.
2 install gwxlibs
3 openserver dev kit
4 gnu tools kit which includes gcc-2.95.3.
5 install MP3 maintainence patch
6 install FSU-threads-3.14  from

                      ftp://ftp.zenez.com/pub/zenez/prgms/FSU-threads-3.14.tar.gz

do "make clean", "make", "make install"

7 download source for mysql-4.1.10.
8 install patches from my site to include files.

                      ftp://ftp.zenez.com/pub/zenez/prgms/types.h.patch

9 run configure-mysql4-osr5blg or configure-mysql4-osr5blgst

                       ftp://ftp.zenez.com/pub/zenez/prgms/configure-mysql4-osr5blg
                       ftp://ftp.zenez.com/pub/zenez/prgms/configure-mysql4-osr5blgst

10 /usr/gnu/bin/make or /usr/gnu/bin/gmake
11 /usr/gnu/bin/make test

Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --based
ir=. --datadir=./var/master-data --skip-innodb --skip-ndbcluster --skip-bdb
--language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Installing Slave Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --based
ir=. --datadir=./var/slave-data --skip-innodb --skip-ndbcluster --skip-bdb     -
-language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

TEST                            RESULT
-------------------------------------------------------
ERROR: /home/osr5/mysql-4.1.10/mysql-test/var/run/master.pid was not created in
400 seconds;  Aborting
make: *** [test] Error 1
[8 Mar 2005 4:05] Boyd GERBER
What I have found is that innodb nolonger works properly on OpenServer 5.0.7.  If I remove innodb the tests all work.
[9 Mar 2005 23:52] Boyd GERBER
Problems with mysql-4.1.10 when running mysql-test-run --debug when compiled with...

CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client --with-berkeley-db=./bdb --with-innodb --with-openssl --with-vio --with-extra-charsets=complex --with-debug=full

$ ./mysql-test-run --debug
Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/master-data --skip-innodb --skip-ndbcluster --skip-bdb--language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 324, 'charset.c'
Error: Freeing wrong aligned pointer at line 357, 'sql_class.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 210, 'lock.cc'
Error: Freeing wrong aligned pointer at line 210, 'lock.cc'
Error: Freeing wrong aligned pointer at line 150, 'sql_string.h'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 169, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 826, 'table.cc'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 169, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 683, 'mi_create.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 683, 'mi_create.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 709, 'table.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 151, 'net_serv.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 405, 'sql_class.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 156, 'queues.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 236, 'sql_base.cc'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 125, 'ft_stopwords.c'
Error: Freeing wrong aligned pointer at line 609, 'mf_keycache.c'
Error: Freeing wrong aligned pointer at line 611, 'mf_keycache.c'
Error: Freeing wrong aligned pointer at line 876, 'set_var.h'
Error: Freeing wrong aligned pointer at line 324, 'sql_list.h'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 143, 'my_file.c'
Error: Freeing wrong aligned pointer at line 968, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 974, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 80, 'mf_tempdir.c'
Error: Freeing wrong aligned pointer at line 977, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 1006, 'mysqld.cc'
Installing Slave Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/slave-data --skip-innodb --skip-ndbcluster --skip-bdb     --language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 324, 'charset.c'
Error: Freeing wrong aligned pointer at line 357, 'sql_class.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 210, 'lock.cc'
Error: Freeing wrong aligned pointer at line 210, 'lock.cc'
Error: Freeing wrong aligned pointer at line 150, 'sql_string.h'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 169, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 826, 'table.cc'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 169, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 1525, 'ha_myisam.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 94, 'mi_dynrec.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 683, 'mi_create.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 683, 'mi_create.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 729, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 170, 'unireg.cc'
Error: Freeing wrong aligned pointer at line 886, 'table.cc'
Error: Freeing wrong aligned pointer at line 905, 'table.cc'
Error: Freeing wrong aligned pointer at line 709, 'table.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 1240, 'mf_iocache.c'
Error: Freeing wrong aligned pointer at line 151, 'net_serv.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 405, 'sql_class.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 156, 'queues.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 91, 'my_open.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 236, 'sql_base.cc'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 104, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 111, 'mi_close.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 270, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 125, 'ft_stopwords.c'
Error: Freeing wrong aligned pointer at line 609, 'mf_keycache.c'
Error: Freeing wrong aligned pointer at line 611, 'mf_keycache.c'
Error: Freeing wrong aligned pointer at line 876, 'set_var.h'
Error: Freeing wrong aligned pointer at line 324, 'sql_list.h'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 143, 'my_file.c'
Error: Freeing wrong aligned pointer at line 968, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 276, 'my_alloc.c'
Error: Freeing wrong aligned pointer at line 974, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 80, 'mf_tempdir.c'
Error: Freeing wrong aligned pointer at line 977, 'mysqld.cc'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 236, 'array.c'
Error: Freeing wrong aligned pointer at line 1006, 'mysqld.cc'
Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

TEST                            RESULT
-------------------------------------------------------
[10 Mar 2005 2:01] Boyd GERBER
If configured with 
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-berkeley-db=./bdb --with-innodb --with-openssl --with-vio 
--with-extra-charsets=complex --with-debug

instead of with
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-berkeley-db=./bdb --with-innodb --with-openssl --with-vio --with-extra-c
harsets=complex --with-debug=full

then this is what happens

Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --based
ir=. --datadir=./var/master-data --skip-innodb --skip-ndbcluster --skip-bdb
--language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Installing Slave Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --based
ir=. --datadir=./var/slave-data --skip-innodb --skip-ndbcluster --skip-bdb     -
-language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

TEST                            RESULT
-------------------------------------------------------
alias                          [ pass ]
alter_table                    [ pass ]
analyse                        [ pass ]
ansi                           [ pass ]
archive                        [ skipped ]
auto_increment                 [ pass ]
backup                         [ pass ]
bdb-alter-table-1              [ pass ]
bdb-alter-table-2              [ pass ]
bdb-crash                      [ pass ]
bdb-deadlock                   [ pass ]
bdb                            [ pass ]
bdb_cache                      [ pass ]
bench_count_distinct           [ pass ]
bigint                         [ pass ]
binary                         [ pass ]
bool                           [ pass ]
bulk_replace                   [ pass ]
case                           [ pass ]
cast                           [ pass ]
check                          [ pass ]
comments                       [ pass ]
compare                        [ pass ]
connect                        [ pass ]
consistent_snapshot            [ pass ]
constraints                    [ pass ]
count_distinct                 [ pass ]
count_distinct2                [ fail ]

Errors are (from /home/osr5/mysql-4.1.10/mysql-test/var/log/mysqltest-time) :
/home/osr5/mysql-4.1.10/client/.libs/mysqltest: At line 75: query 'insert into t
1 values('2867')' failed: 2013: Lost connection to MySQL server during query
(the last lines may be the most important ones)

Ending Tests
Shutting-down MySQL daemon

master not cooperating with mysqladmin, will try manual kill
kill: 12472 no such process
master refused to die. Sending SIGKILL
kill: 12472 no such process
Master shutdown finished
Slave shutdown finished
Resuming Tests

count_distinct3                [ fail ]

Errors are (from /home/osr5/mysql-4.1.10/mysql-test/var/log/mysqltest-time) :
/home/osr5/mysql-4.1.10/client/.libs/mysqltest: At line 39: query 'INSERT INTO t
2 (id, grp, id_rev) SELECT id, grp, id_rev FROM t1' failed: 2013: Lost connectio
n to MySQL server during query
(the last lines may be the most important ones)

Ending Tests
Shutting-down MySQL daemon

master not cooperating with mysqladmin, will try manual kill
kill: 12545 no such process
master refused to die. Sending SIGKILL
kill: 12545 no such process
Master shutdown finished
Slave shutdown finished
Resuming Tests

ERROR: /home/osr5/mysql-4.1.10/mysql-test/var/run/master.pid was not created in
400 seconds;  Aborting

the test goes to func_crypt without any debug with ...
CC=gcc CXX=gcc ./configure --prefix=/usr/local/mysql --enable-thread-safe-client
 --with-berkeley-db=./bdb --with-innodb --with-openssl --with-vio 
--with-extra-charsets=complex 

This makes debuging a real pain.
[10 Mar 2005 2:35] Boyd GERBER
MySQL 4.1.9 on OpenServer 5.0.7.  failing several tests: but not as many as 4.1.10.

Failed 10/259 tests, 96.13% successful.

The log files in /usr/share/mysql-test/var/log may give you some hint
of what when wrong.
If you want to report this error, please read first the documentation at
http://www.mysql.com/doc/en/MySQL_test_suite.html

mysql-test-run: *** Failing the test(s): client_test drop func_crypt
ps_1general rpl000001 rpl000009 rpl_change_master rpl_create_database
rpl_master_pos_wait show_check
[10 Mar 2005 19:43] Boyd GERBER
This shows the looping when running

$ ./mysql-test-run --debug rpl000001

It sits for hours with adding only to slave.trace, master.trace ...
master.err slave.err  mysqltest.trace never seem to change.

# l
total 219544
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     768965 Mar 10 19:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     430518 Mar 10 19:38 master.log
-rw-rw----   1 gerberb  zenez    60240146 Mar 10 19:38 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     773761 Mar 10 19:38 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       3782 Mar 10 19:29 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    34520881 Mar 10 19:38 slave.trace

and some time later...
# l
total 224926
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     768965 Mar 10 19:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     438015 Mar 10 19:42 master.log
-rw-rw----   1 gerberb  zenez    61678109 Mar 10 19:42 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     774685 Mar 10 19:42 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       3782 Mar 10 19:29 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    35818639 Mar 10 19:42 slave.trace

master.err
--------------------------------------------------------------------
CURRENT_TEST: rpl000001
050310 22:18:45 [Warning] setrlimit could not change the size of core files to 'infinity';  We may not be able to generate a core file on signals
InnoDB: The first specified data file ./ibdata1 did not exist:
InnoDB: a new database to be created!
050310 22:18:45  InnoDB: Setting file ./ibdata1 size to 50 MB
InnoDB: Database physically writes the file full: wait...
050310 22:18:49  InnoDB: Log file ./ib_logfile0 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile0 size to 5 MB
InnoDB: Database physically writes the file full: wait...
050310 22:18:50  InnoDB: Log file ./ib_logfile1 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile1 size to 5 MB
InnoDB: Database physically writes the file full: wait...
InnoDB: Doublewrite buffer not found: creating new
InnoDB: Doublewrite buffer created
InnoDB: Creating foreign key constraint system tables
InnoDB: Foreign key constraint system tables created
050310 22:18:51  InnoDB: Started; log sequence number 0 0
/home/osr5/mysql-4.1.10/sql/mysqld: ready for connections.
Version: '4.1.10-debug-log'  socket: '/home/osr5/mysql-4.1.10/mysql-test/var/tmp/master.sock'  port: 9306  Source distribution

-------------------------------------------------------------------------
mysqltest.trace last 100 lines
-------------------------------------------------------------------------
race
# tail -100 mysqltest.trace
| | | | <mysql_next_result
| | | | >free_replace
| | | | <free_replace
| | | | >var_set
| | | | | enter: var_name: '$mysql_errno' = '0' (length: 1)
| | | | | >hash_search
| | | | | | exit: found key at 2
| | | | | <hash_search
/home/osr5/mysql-4.1.10/client/.libs/mysqltest: missing DBUG_RETURN or DBUG_VOID_RETURN macro in function "var_set"
| | | >read_query
| | | | >my_malloc
| | | | | my: size: 664  my_flags: 16
| | | | | exit: ptr: 0x811a524
| | | | <my_malloc
| | | | >read_line
| | | | <read_line
| | | | info: query: #hope this is long enough for I/O thread to fetch over 16Krelay log data
| | | | >my_malloc
| | | | | my: size: 74  my_flags: 16
| | | | | exit: ptr: 0x811a7c0
| | | | <my_malloc
| | | <read_query
| | | >read_query
| | | | >my_malloc
| | | | | my: size: 664  my_flags: 16
| | | | | exit: ptr: 0x811a810
| | | | <my_malloc
| | | | >read_line
| | | | <read_line
| | | | info: query: sleep 3
| | | | >my_malloc
| | | | | my: size: 8  my_flags: 16
| | | | | exit: ptr: 0x80adfb8
| | | | <my_malloc
| | | <read_query
| | | >get_query_type
| | | | >find_type
| | | | | enter: x: 'sleep'  lib: 0x805c2bc
| | | | <find_type
| | | <get_query_type
| | | >read_query
| | | | >my_malloc
| | | | | my: size: 664  my_flags: 16
| | | | | exit: ptr: 0x811aaac
| | | | <my_malloc
| | | | >read_line
| | | | <read_line
| | | | info: query: select release_lock("hold_slave")
| | | | >my_malloc
| | | | | my: size: 34  my_flags: 16
| | | | | exit: ptr: 0x811ad48
| | | | <my_malloc
| | | <read_query
| | | >get_query_type
| | | | >find_type
| | | | | enter: x: 'select'  lib: 0x805c2bc
| | | | | exit: Couldn't find type
| | | | <find_type
| | | <get_query_type
| | | >run_query_normal
| | | | enter: flags: 3
| | | | enter: query: 'select release_lock("hold_slave")'
| | | | >mysql_send_query
| | | | | enter: rpl_parse: 0  rpl_pivot: 1
| | | | <mysql_send_query
| | | | >net_clear
| | | | | >vio_blocking
| | | | | | enter: set_blocking_mode: 0  old_mode: 1
| | | | | | exit: 0
| | | | | <vio_blocking
| | | | | >vio_read
| | | | | | enter: sd=8, buf=8108e70, size=8192
| | | | | | vio_error: Got error 90 during read
| | | | | | exit: -1
| | | | | <vio_read
| | | | | >vio_blocking
| | | | | | enter: set_blocking_mode: 1  old_mode: 0
| | | | | | exit: 0
| | | | | <vio_blocking
| | | | <net_clear
| | | | >net_write_command
| | | | | enter: length: 33
| | | | <net_write_command
| | | | >net_flush
| | | | | >vio_is_blocking
| | | | | | exit: 1
| | | | | <vio_is_blocking
| | | | | >net_real_write
| | | | | | >vio_write
| | | | | | | enter: sd=8, buf=8108e70, size=38
| | | | | | | exit: 38
| | | | | | <vio_write
| | | | | <net_real_write
| | | | <net_flush
| | | | >cli_read_query_result
| | | | | >vio_is_blocking
| | | | | | exit: 1
| | | | | <vio_is_blocking
| | | | | >vio_read
| | | | | | enter: sd=8, buf=8108e70, size=4

master.trace 1000 last lines that keep repeating about the same
-------------------------------------------------------------------------
query_cache_end_of_result
T@44   : | | <query_cache_end_of_result
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | <mysql_parse
T@44   : | info: query ready
T@44   : | >free_root
T@44   : | | enter: root: 0x8644040  flags: 1
T@44   : | <free_root
T@44   : <dispatch_command
T@44   : >do_command
T@44   : | >vio_is_blocking
T@44   : | | exit: 0
T@44   : | <vio_is_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | vio_error: Got error 90 during read
T@44   : | | exit: -1
T@44   : | <vio_read
T@44   : | info: vio_read returned -1,  errno: 90
T@44   : | >thr_alarm
T@44   : | | enter: thread: T@44  sec: 28800
T@44   : | <thr_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 1  old_mode: 0
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | exit: 4
T@44   : | <vio_read
T@44   : | packet_header: Memory: aa34f74  Bytes: (4)
1A 00 00 00
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=26
T@44   : | | exit: 26
T@44   : | <vio_read
T@44   : | >thr_end_alarm
T@44   : | <thr_end_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 0  old_mode: 1
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | info: Command on TCP/IP (18) = 3 (Query)
T@44   : <do_command
T@44   : >dispatch_command
T@44   : | >my_b_flush_io_cache
T@44   : | | >my_write
T@44   : | | | my: Fd: 6  Buffer: 0x863cac0  Count: 48  MyFlags: 20
T@44   : | | <my_write
T@44   : | <my_b_flush_io_cache
T@44   : | query: SELECT @@GLOBAL.TIME_ZONE
T@44   : | >mysql_parse
T@44   : | | >mysql_init_query
T@44   : | | | >mysql_reset_thd_for_next_command
T@44   : | | | <mysql_reset_thd_for_next_command
T@44   : | | <mysql_init_query
T@44   : | | >Query_cache::send_result_to_client
T@44   : | | <Query_cache::send_result_to_client
T@44   : | | >hash_search
T@44   : | | | exit: found key at 92
T@44   : | | <hash_search
T@44   : | | >mysql_execute_command
T@44   : | | | >check_access
T@44   : | | | | enter: db: '*any*'  want_access: 1  master_access: 2097151
T@44   : | | | <check_access
T@44   : | | | >open_and_lock_tables
T@44   : | | | | >open_tables
T@44   : | | | | <open_tables
T@44   : | | | <open_and_lock_tables
T@44   : | | | >Query_cache::store_query
T@44   : | | | <Query_cache::store_query
T@44   : | | | >handle_select
T@44   : | | | | >mysql_select
T@44   : | | | | | >JOIN::prepare
T@44   : | | | | | | >setup_tables
T@44   : | | | | | | <setup_tables
T@44   : | | | | | | >setup_wild
T@44   : | | | | | | <setup_wild
T@44   : | | | | | | >setup_fields
T@44   : | | | | | | <setup_fields
T@44   : | | | | | | >setup_without_group
T@44   : | | | | | | | >setup_conds
T@44   : | | | | | | | <setup_conds
T@44   : | | | | | | <setup_without_group
T@44   : | | | | | | >setup_procedure
T@44   : | | | | | | <setup_procedure
T@44   : | | | | | | >alloc_func_list
T@44   : | | | | | | <alloc_func_list
T@44   : | | | | | <JOIN::prepare
T@44   : | | | | | >JOIN::optimize
T@44   : | | | | | | >optimize_cond
T@44   : | | | | | | <optimize_cond
T@44   : | | | | | <JOIN::optimize
T@44   : | | | | | >JOIN::exec
T@44   : | | | | | | >send_fields
T@44   : | | | | | | | packet_header: Memory: a9dd490  Bytes: (4)
01 00 00 01
T@44   : | | | | | | | >Protocol::write
T@44   : | | | | | | | <Protocol::write
T@44   : | | | | | | | packet_header: Memory: a9dd460  Bytes: (4)
28 00 00 02
T@44   : | | | | | | | packet_header: Memory: a9dd4a0  Bytes: (4)
01 00 00 03
T@44   : | | | | | | <send_fields
T@44   : | | | | | | >send_data
T@44   : | | | | | | <send_data
T@44   : | | | | | | >Protocol::write
T@44   : | | | | | | <Protocol::write
T@44   : | | | | | | packet_header: Memory: a9dd220  Bytes: (4)
07 00 00 04
T@44   : | | | | | | >send_eof
T@44   : | | | | | | | packet_header: Memory: a9dd560  Bytes: (4)
05 00 00 05
T@44   : | | | | | | | >net_flush
T@44   : | | | | | | | | >vio_is_blocking
T@44   : | | | | | | | | | exit: 0
T@44   : | | | | | | | | <vio_is_blocking
T@44   : | | | | | | | | >net_real_write
T@44   : | | | | | | | | | >vio_write
T@44   : | | | | | | | | | | enter: sd=18, buf=aa34f74, size=74
T@44   : | | | | | | | | | | exit: 74
T@44   : | | | | | | | | | <vio_write
T@44   : | | | | | | | | <net_real_write
T@44   : | | | | | | | <net_flush
T@44   : | | | | | | <send_eof
T@44   : | | | | | <JOIN::exec
T@44   : | | | | | >JOIN::cleanup
T@44   : | | | | | | >JOIN::join_free
T@44   : | | | | | | <JOIN::join_free
T@44   : | | | | | <JOIN::cleanup
T@44   : | | | | <mysql_select
T@44   : | | | | info: res: 0  report_error: 0
T@44   : | | | <handle_select
T@44   : | | <mysql_execute_command
T@44   : | | >query_cache_end_of_result
T@44   : | | <query_cache_end_of_result
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | <mysql_parse
T@44   : | info: query ready
T@44   : | >free_root
T@44   : | | enter: root: 0x8644040  flags: 1
T@44   : | <free_root
T@44   : <dispatch_command
T@44   : >do_command
T@44   : | >vio_is_blocking
T@44   : | | exit: 0
T@44   : | <vio_is_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | vio_error: Got error 90 during read
T@44   : | | exit: -1
T@44   : | <vio_read
T@44   : | info: vio_read returned -1,  errno: 90
T@44   : | >thr_alarm
T@44   : | | enter: thread: T@44  sec: 28800
T@44   : | <thr_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 1  old_mode: 0
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | exit: 4
T@44   : | <vio_read
T@44   : | packet_header: Memory: aa34f74  Bytes: (4)
1F 00 00 00
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=31
T@44   : | | exit: 31
T@44   : | <vio_read
T@44   : | >thr_end_alarm
T@44   : | <thr_end_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 0  old_mode: 1
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | info: Command on TCP/IP (18) = 21 (Register Slave)
T@44   : <do_command
T@44   : >dispatch_command
T@44   : | >check_access
T@44   : | | enter: db: '*any*'  want_access: 524288  master_access: 2097151
T@44   : | <check_access
T@44   : | >my_malloc
T@44   : | | my: size: 132  my_flags: 16
T@44   : | | exit: ptr: 0xa9adf24
T@44   : | <my_malloc
T@44   : | >hash_search
T@44   : | | exit: found key at 0
T@44   : | <hash_search
T@44   : | >hash_delete
T@44   : | | >my_free
T@44   : | | | my: ptr: 0xa9ade9c
T@44   : | | <my_free
T@44   : | <hash_delete
T@44   : | >send_ok
T@44   : | | info: affected_rows: 0  id: 0  status: 2  warning_count: 0
T@44   : | | packet_header: Memory: a9dd9a0  Bytes: (4)
07 00 00 01
T@44   : | | >net_flush
T@44   : | | | >vio_is_blocking
T@44   : | | | | exit: 0
T@44   : | | | <vio_is_blocking
T@44   : | | | >net_real_write
T@44   : | | | | >vio_write
T@44   : | | | | | enter: sd=18, buf=aa34f74, size=11
T@44   : | | | | | exit: 11
T@44   : | | | | <vio_write
T@44   : | | | <net_real_write
T@44   : | | <net_flush
T@44   : | <send_ok
T@44   : | >free_root
T@44   : | | enter: root: 0x8644040  flags: 1
T@44   : | <free_root
T@44   : <dispatch_command
T@44   : >do_command
T@44   : | >vio_is_blocking
T@44   : | | exit: 0
T@44   : | <vio_is_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | vio_error: Got error 90 during read
T@44   : | | exit: -1
T@44   : | <vio_read
T@44   : | info: vio_read returned -1,  errno: 90
T@44   : | >thr_alarm
T@44   : | | enter: thread: T@44  sec: 28800
T@44   : | <thr_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 1  old_mode: 0
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | exit: 4
T@44   : | <vio_read
T@44   : | packet_header: Memory: aa34f74  Bytes: (4)
11 00 00 00
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=17
T@44   : | | exit: 17
T@44   : | <vio_read
T@44   : | >thr_end_alarm
T@44   : | <thr_end_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 0  old_mode: 1
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | info: Command on TCP/IP (18) = 3 (Query)
T@44   : <do_command
T@44   : >dispatch_command
T@44   : | >my_b_flush_io_cache
T@44   : | | >my_write
T@44   : | | | my: Fd: 6  Buffer: 0x863cac0  Count: 39  MyFlags: 20
T@44   : | | <my_write
T@44   : | <my_b_flush_io_cache
T@44   : | query: SHOW SLAVE HOSTS
T@44   : | >mysql_parse
T@44   : | | >mysql_init_query
T@44   : | | | >mysql_reset_thd_for_next_command
T@44   : | | | <mysql_reset_thd_for_next_command
T@44   : | | <mysql_init_query
T@44   : | | >Query_cache::send_result_to_client
T@44   : | | <Query_cache::send_result_to_client
T@44   : | | >mysql_execute_command
T@44   : | | | >show_slave_hosts
T@44   : | | | | >send_fields
T@44   : | | | | | packet_header: Memory: a9dd590  Bytes: (4)
01 00 00 01
T@44   : | | | | | >Protocol::write
T@44   : | | | | | <Protocol::write
T@44   : | | | | | packet_header: Memory: a9dd560  Bytes: (4)
1F 00 00 02
T@44   : | | | | | >Protocol::write
T@44   : | | | | | <Protocol::write
T@44   : | | | | | packet_header: Memory: a9dd560  Bytes: (4)
1A 00 00 03
T@44   : | | | | | >Protocol::write
T@44   : | | | | | <Protocol::write
T@44   : | | | | | packet_header: Memory: a9dd560  Bytes: (4)
1A 00 00 04
T@44   : | | | | | >Protocol::write
T@44   : | | | | | <Protocol::write
T@44   : | | | | | packet_header: Memory: a9dd560  Bytes: (4)
27 00 00 05
T@44   : | | | | | >Protocol::write
T@44   : | | | | | <Protocol::write
T@44   : | | | | | packet_header: Memory: a9dd560  Bytes: (4)
1F 00 00 06
T@44   : | | | | | packet_header: Memory: a9dd5a0  Bytes: (4)
01 00 00 07
T@44   : | | | | <send_fields
T@44   : | | | | >Protocol::write
T@44   : | | | | <Protocol::write
T@44   : | | | | packet_header: Memory: a9dd670  Bytes: (4)
15 00 00 08
T@44   : | | | | >send_eof
T@44   : | | | | | packet_header: Memory: a9dd660  Bytes: (4)
05 00 00 09
T@44   : | | | | | >net_flush
T@44   : | | | | | | >vio_is_blocking
T@44   : | | | | | | | exit: 0
T@44   : | | | | | | <vio_is_blocking
T@44   : | | | | | | >net_real_write
T@44   : | | | | | | | >vio_write
T@44   : | | | | | | | | enter: sd=18, buf=aa34f74, size=217
T@44   : | | | | | | | | exit: 217
T@44   : | | | | | | | <vio_write
T@44   : | | | | | | <net_real_write
T@44   : | | | | | <net_flush
T@44   : | | | | <send_eof
T@44   : | | | <show_slave_hosts
T@44   : | | <mysql_execute_command
T@44   : | | >query_cache_end_of_result
T@44   : | | <query_cache_end_of_result
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | | >my_free
T@44   : | | | my: ptr: 0x0
T@44   : | | <my_free
T@44   : | <mysql_parse
T@44   : | info: query ready
T@44   : | >free_root
T@44   : | | enter: root: 0x8644040  flags: 1
T@44   : | <free_root
T@44   : <dispatch_command
T@44   : >do_command
T@44   : | >vio_is_blocking
T@44   : | | exit: 0
T@44   : | <vio_is_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | vio_error: Got error 90 during read
T@44   : | | exit: -1
T@44   : | <vio_read
T@44   : | info: vio_read returned -1,  errno: 90
T@44   : | >thr_alarm
T@44   : | | enter: thread: T@44  sec: 28800
T@44   : | <thr_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 1  old_mode: 0
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=4
T@44   : | | exit: 4
T@44   : | <vio_read
T@44   : | packet_header: Memory: aa34f74  Bytes: (4)
1C 00 00 00
T@44   : | >vio_read
T@44   : | | enter: sd=18, buf=aa34f74, size=28
T@44   : | | exit: 28
T@44   : | <vio_read
T@44   : | >thr_end_alarm
T@44   : | <thr_end_alarm
T@44   : | >vio_blocking
T@44   : | | enter: set_blocking_mode: 0  old_mode: 1
T@44   : | | exit: 0
T@44   : | <vio_blocking
T@44   : | info: Command on TCP/IP (18) = 18 (Binlog Dump)
T@44   : <do_command
T@44   : >dispatch_command
T@44   : | >my_b_flush_io_cache
T@44   : | | >my_write
T@44   : | | | my: Fd: 6  Buffer: 0x863cac0  Count: 60  MyFlags: 20
T@44   : | | <my_write
T@44   : | <my_b_flush_io_cache
T@44   : | >mysql_binlog_send
T@44   : | | enter: log_ident: 'master-bin.000001'  pos: 768965
T@44   : | | >find_log_pos
T@44   : | | | enter: log_name: /home/osr5/mysql-4.1.10/mysql-test/var/log/master-bin.000001
T@44   : | | | >reinit_io_cache
T@44   : | | | | enter: cache: 0x84ea4f0 type: 0  seek_offset: 0  clear_cache: 0
T@44   : | | | <reinit_io_cache
T@44   : | | | info: Found log file entry
T@44   : | | <find_log_pos
T@44   : | | >open_binlog
T@44   : | | | >my_open
T@44   : | | | | my: Name: '/home/osr5/mysql-4.1.10/mysql-test/var/log/master-bin.000001'  Flags: 0  MyFlags: 16
T@44   : | | | <my_open
T@44   : | | | >my_malloc
T@44   : | | | | my: size: 61  my_flags: 16
T@44   : | | | | exit: ptr: 0xa9adfac
T@44   : | | | <my_malloc
T@44   : | | | exit: fd: 19
T@44   : | | | >init_io_cache
T@44   : | | | | enter: cache: 0xa9dd6d8  type: 0  pos: 0
T@44   : | | | | >my_malloc
T@44   : | | | | | my: size: 8192  my_flags: 16
T@44   : | | | | | exit: ptr: 0xa9e2138
T@44   : | | | | <my_malloc
T@44   : | | | | info: init_io_cache: cachesize = 8192
T@44   : | | | <init_io_cache
T@44   : | | | >_my_b_read
T@44   : | | | | >my_seek
T@44   : | | | | | my: Fd: 19  Hpos: 0  Pos: 0  Whence: 0  MyFlags: 0
T@44   : | | | | <my_seek
T@44   : | | | | >my_read
T@44   : | | | | | my: Fd: 19  Buffer: 0xa9e2138  Count: 8192  MyFlags: 16
T@44   : | | | | <my_read
T@44   : | | | <_my_b_read
T@44   : | | <open_binlog
T@44   : | | >my_seek
T@44   : | | | my: Fd: 19  Hpos: 0  Pos: 0  Whence: 2  MyFlags: 0
T@44   : | | | exit: pos: 768965
T@44   : | | <my_seek
T@44   : | | >my_b_seek
T@44   : | | | enter: pos: 768965
T@44   : | | <my_b_seek
T@44   : | | >my_free
T@44   : | | | my: ptr: 0xa9efe74
T@44   : | | <my_free
T@44   : | | >my_malloc
T@44   : | | | my: size: 24  my_flags: 16
T@44   : | | | exit: ptr: 0xa9efe74
T@44   : | | <my_malloc
T@44   : | | >my_realloc
T@44   : | | | my: ptr: 0xa9efe74  size: 32  my_flags: 16
T@44   : | | | exit: ptr: 0xa9efe74
T@44   : | | <my_realloc
T@44   : | | >my_realloc
T@44   : | | | my: ptr: 0xa9efe74  size: 48  my_flags: 16
T@44   : | | | exit: ptr: 0xa9efe74
T@44   : | | <my_realloc
T@44   : | | packet_header: Memory: a9dd620  Bytes: (4)
2D 00 00 01
T@44   : | | >my_free
T@44   : | | | my: ptr: 0xa9efe74
T@44   : | | <my_free
T@44   : | | >read_log_event
T@44   : | | | >_my_b_read
T@44   : | | | | >my_seek
T@44   : | | | | | my: Fd: 19  Hpos: 0  Pos: 768965  Whence: 0  MyFlags: 0
T@44   : | | | | <my_seek
T@44   : | | | | >my_read
T@44   : | | | | | my: Fd: 19  Buffer: 0xa9e2138  Count: 5179  MyFlags: 16
T@44   : | | | | | warning: Read only 0 bytes off 5179 from 19, errno: -1
T@44   : | | | | <my_read
T@44   : | | | <_my_b_read
T@44   : | | | error: file->error: 0
T@44   : | | <read_log_event
T@44   : | | >net_flush
T@44   : | | | >vio_is_blocking
T@44   : | | | | exit: 0
T@44   : | | | <vio_is_blocking
T@44   : | | | >net_real_write
T@44   : | | | | >vio_write
T@44   : | | | | | enter: sd=18, buf=aa34f74, size=49
T@44   : | | | | | exit: 49
T@44   : | | | | <vio_write
T@44   : | | | <net_real_write
T@44   : | | <net_flush
T@44   : | | >read_log_event
T@44   : | | | >_my_b_read
T@44   : | | | | >my_read
T@44   : | | | | | my: Fd: 19  Buffer: 0xa9e2138  Count: 5179  MyFlags: 16
T@44   : | | | | | warning: Read only 0 bytes off 5179 from 19, errno: -1
T@44   : | | | | <my_read
T@44   : | | | <_my_b_read
T@44   : | | | error: file->error: 0
T@44   : | | <read_log_event
T@44   : | | wait: waiting for data in binary log
T@44   : | | >wait_for_update
T@43   : | | <wait_for_update
T@43   : | | wait: binary log received update
T@43   : | | >end_io_cache
T@43   : | | | >my_b_flush_io_cache
T@43   : | | | <my_b_flush_io_cache
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xaa3af94
T@43   : | | | <my_free
T@43   : | | <end_io_cache
T@43   : | | >my_close
T@43   : | | | my: fd: 25  MyFlags: 16
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0x8645f3c
T@43   : | | | <my_free
T@43   : | | <my_close
T@43   : | | >send_eof
T@43   : | | | packet_header: Memory: aa2c484  Bytes: (4)
05 00 00 02
T@43   : | | | >net_flush
T@43   : | | | | >vio_is_blocking
T@43   : | | | | | exit: 0
T@43   : | | | | <vio_is_blocking
T@43   : | | | | >net_real_write
T@43   : | | | | | >vio_write
T@43   : | | | | | | enter: sd=24, buf=a9de12c, size=9
T@43   : | | | | | | vio_error: Got error on write: 108
T@43   : | | | | | | exit: -1
T@43   : | | | | | <vio_write
T@43   : | | | | <net_real_write
T@43   : | | | <net_flush
T@43   : | | <send_eof
T@43   : | <mysql_binlog_send
T@43   : | >hash_search
T@43   : | | exit: found key at 0
T@43   : | <hash_search
T@43   : | >free_root
T@43   : | | enter: root: 0xa9e8178  flags: 1
T@43   : | <free_root
T@43   : <dispatch_command
T@43   : >free_root
T@43   : | enter: root: 0xa9e8178  flags: 0
T@43   : | >my_free
T@43   : | | my: ptr: 0xaa38f80
T@43   : | <my_free
T@43   : <free_root
T@43   : >close_connection
T@43   : | enter: fd: TCP/IP (24)  error: ''
T@43   : | >vio_close
T@43   : | | vio_error: close() failed, error: 111
T@43   : | <vio_close
T@43   : <close_connection
T@43   : >end_thread
T@43   : | >THD::cleanup
T@43   : | | >ha_rollback_trans
T@43   : | | | >reinit_io_cache
T@43   : | | | | enter: cache: 0xa9e92b8 type: 1  seek_offset: 0  clear_cache: 1
T@43   : | | | <reinit_io_cache
T@43   : | | <ha_rollback_trans
T@43   : | | >mysql_ha_flush
T@43   : | | | enter: tables: 0  mode_flags: 0x02
T@43   : | | <mysql_ha_flush
T@43   : | | >hash_free
T@43   : | | | enter: hash: 0xa9e9244d
T@43   : | | <hash_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa8d6604
T@43   : | | <my_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa9ad554
T@43   : | | <my_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0x8645fcc
T@43   : | | <my_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa8d6668
T@43   : | | <my_free
T@43   : | | >hash_free
T@43   : | | | enter: hash: 0xa9e89e8d
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xa8d6360
T@43   : | | | <my_free
T@43   : | | <hash_free
T@43   : | <THD::cleanup
T@43   : | >~THD()
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa9adc3c
T@43   : | | <my_free
T@43   : | | >net_end
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xa9de12c
T@43   : | | | <my_free
T@43   : | | <net_end
T@43   : | | >close_cached_file
T@43   : | | | >end_io_cache
T@43   : | | | | >my_free
T@43   : | | | | | my: ptr: 0xa9f4ea0
T@43   : | | | | <my_free
T@43   : | | | <end_io_cache
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xa9eb8c0
T@43   : | | | <my_free
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0x85cbdf0
T@43   : | | | <my_free
T@43   : | | <close_cached_file
T@43   : | | info: freeing host
T@43   : | | >my_free
T@43   : | | | my: ptr: 0x8635990
T@43   : | | <my_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0x85d84d4
T@43   : | | <my_free
T@43   : | | >my_free
T@43   : | | | my: ptr: 0x86359a0
T@43   : | | <my_free
T@43   : | | >free_root
T@43   : | | | enter: root: 0xa9e8988  flags: 0
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xa9e9504
T@43   : | | | <my_free
T@43   : | | <free_root
T@43   : | | >free_root
T@43   : | | | enter: root: 0xa9e93b0  flags: 0
T@43   : | | | >my_free
T@43   : | | | | my: ptr: 0xa9ee6a0
T@43   : | | | <my_free
T@43   : | | <free_root
T@43   : | <~THD()
T@43   : | >free_root
T@43   : | | enter: root: 0xa9e8c18  flags: 0
T@43   : | <free_root
T@43   : | >hash_free
T@43   : | | enter: hash: 0xa9e8bd8d
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa9eb7a8
T@43   : | | <my_free
T@43   : | <hash_free
T@43   : | >hash_free
T@43   : | | enter: hash: 0xa9e8ba0d
T@43   : | | >my_free
T@43   : | | | my: ptr: 0xa8d6470
T@43   : | | <my_free
T@43   : | <hash_free
T@43   : | >my_free
T@43   : | | my: ptr: 0xa8d6558
T@43   : | <my_free
T@43   : | >free_root
T@43   : | | enter: root: 0xa9e8178  flags: 0
T@43   : | <free_root
T@43   : | >my_free
T@43   : | | my: ptr: 0xa9e8168
T@43   : | <my_free
T@43   : | info: sending a broadcast
T@43   : | info: unlocked thread_count mutex
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
-------------------------------------------------------------------------

slave.err
-------------------------------------------------------------------------

CURRENT_TEST: rpl000001
050310 22:18:52 [Warning] setrlimit could not change the size of core files to 'infinity';  We may not be able to generate a core file on signals
InnoDB: The first specified data file ./ibdata1 did not exist:
InnoDB: a new database to be created!
050310 22:18:52  InnoDB: Setting file ./ibdata1 size to 10 MB
InnoDB: Database physically writes the file full: wait...
050310 22:18:53  InnoDB: Log file ./ib_logfile0 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile0 size to 5 MB
InnoDB: Database physically writes the file full: wait...
050310 22:18:53  InnoDB: Log file ./ib_logfile1 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile1 size to 5 MB
InnoDB: Database physically writes the file full: wait...
InnoDB: Doublewrite buffer not found: creating new
InnoDB: Doublewrite buffer created
InnoDB: Creating foreign key constraint system tables
InnoDB: Foreign key constraint system tables created
050310 22:18:54  InnoDB: Started; log sequence number 0 0
/home/osr5/mysql-4.1.10/sql/mysqld: ready for connections.
Version: '4.1.10-debug-log'  socket: '/home/osr5/mysql-4.1.10/mysql-test/var/tmp/slave.sock'  port: 9308  Source distribution
050310 22:18:55 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:9306',  replication started in log 'FIRST' at position 4
050310 22:18:55 [Note] Slave SQL thread initialized, starting replication in log 'FIRST' at position 0, relay log '/home/osr5/mysql-4.1.10/mysql-test/var/log/slave-relay-bin.000001' position: 4
050310 22:18:55 [ERROR] Slave I/O thread killed while reading event
050310 22:18:55 [ERROR] Slave I/O thread exiting, read up to log 'master-bin.000001', position 1569
050310 22:18:55 [ERROR] Error reading relay log event: slave SQL thread was killed
050310 22:18:55 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 1569, relay log '/home/osr5/mysql-4.1.10/mysql-test/var/log/slave-relay-bin.000002' position: 1613
050310 22:18:55 [ERROR] Slave I/O thread: error connecting to master 'root@127.0.0.1:9306': Error: 'Access denied for user 'root'@'localhost' (using password: NO)'  errno: 1045  retry-time: 1  retries: 10
050310 22:18:58 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:9306',  replication started in log 'master-bin.000001' at position 1569
050310 22:19:00 [ERROR] Slave I/O thread killed while reading event
050310 22:19:00 [ERROR] Slave I/O thread exiting, read up to log 'master-bin.000001', position 1927
050310 22:19:00 [ERROR] Error reading relay log event: slave SQL thread was killed
050310 22:19:27 [Note] Slave SQL thread initialized, starting replication in log 'FIRST' at position 0, relay log '/home/osr5/mysql-4.1.10/mysql-test/var/log/slave-relay-bin.000001' position: 4
050310 22:19:27 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:9306',  replication started in log 'FIRST' at position 4
050310 22:24:14 [ERROR] Slave I/O thread: error reconnecting to master 'root@127.0.0.1:9306': Error: ''  errno: 1159  retry-time: 1  retries: 10
050310 22:24:15 [Note] Slave: connected to master 'root@127.0.0.1:9306',replication resumed in log 'master-bin.000001' at position 768965
050310 22:28:25 [ERROR] Slave I/O thread: error reconnecting to master 'root@127.0.0.1:9306': Error: ''  errno: 1159  retry-time: 1  retries: 10
050310 22:28:26 [Note] Slave: connected to master 'root@127.0.0.1:9306',replication resumed in log 'master-bin.000001' at position 768965

last 1000 lines of slave.trace while running.
-------------------------------------------------------------------------
11   : | | | >vio_blocking
T@11   : | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | exit: 0
T@11   : | | | <vio_blocking
T@11   : | | | info: status: 2  warning_count:  0
T@11   : | | | exit: Got 1 rows
T@11   : | | <cli_read_rows
T@11   : | <mysql_store_result
T@11   : | >mysql_fetch_row
T@11   : | <mysql_fetch_row
T@11   : | >mysql_free_result
T@11   : | | enter: mysql_res: a8589ec
T@11   : | | >free_root
T@11   : | | | enter: root: 0xa858a58  flags: 0
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa9b68c0
T@11   : | | | <my_free
T@11   : | | <free_root
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa858a48
T@11   : | | <my_free
T@11   : | | >free_root
T@11   : | | | enter: root: 0xa858a08  flags: 0
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa9fad6c
T@11   : | | | <my_free
T@11   : | | <free_root
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa8589ec
T@11   : | | <my_free
T@11   : | <mysql_free_result
T@11   : | >net_clear
T@11   : | | >vio_blocking
T@11   : | | | enter: set_blocking_mode: 0  old_mode: 1
T@11   : | | | exit: 0
T@11   : | | <vio_blocking
T@11   : | | >vio_read
T@11   : | | | enter: sd=23, buf=a9735a4, size=16384
T@11   : | | | vio_error: Got error 90 during read
T@11   : | | | exit: -1
T@11   : | | <vio_read
T@11   : | | >vio_blocking
T@11   : | | | enter: set_blocking_mode: 1  old_mode: 0
T@11   : | | | exit: 0
T@11   : | | <vio_blocking
T@11   : | <net_clear
T@11   : | >net_write_command
T@11   : | | enter: length: 30
T@11   : | <net_write_command
T@11   : | >net_flush
T@11   : | | >vio_is_blocking
T@11   : | | | exit: 1
T@11   : | | <vio_is_blocking
T@11   : | | >net_real_write
T@11   : | | | >thr_alarm
T@11   : | | | | enter: thread: T@11  sec: 60
T@11   : | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | <thr_alarm
T@11   : | | | >vio_write
T@11   : | | | | enter: sd=23, buf=a9735a4, size=35
T@11   : | | | | exit: 35
T@11   : | | | <vio_write
T@11   : | | | >thr_end_alarm
T@11   : | | | <thr_end_alarm
T@11   : | | | >vio_blocking
T@11   : | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | exit: 0
T@11   : | | | <vio_blocking
T@11   : | | <net_real_write
T@11   : | <net_flush
T@11   : | >vio_is_blocking
T@11   : | | exit: 1
T@11   : | <vio_is_blocking
T@11   : | >thr_alarm
T@11   : | | enter: thread: T@11  sec: 10
T@11   : | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | <thr_alarm
T@11   : | >vio_read
T@11   : | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | exit: 4
T@11   : | <vio_read
T@11   : | packet_header: Memory: a9735a4  Bytes: (4)
07 00 00 01
T@11   : | >vio_read
T@11   : | | enter: sd=23, buf=a9735a4, size=7
T@11   : | | exit: 7
T@11   : | <vio_read
T@11   : | >thr_end_alarm
T@11   : | <thr_end_alarm
T@11   : | >vio_blocking
T@11   : | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | exit: 0
T@11   : | <vio_blocking
T@11   : | >update_slave_list
T@11   : | | >mysql_real_query
T@11   : | | | enter: handle: a858374
T@11   : | | | query: Query = 'SHOW SLAVE HOSTS'
T@11   : | | | >mysql_send_query
T@11   : | | | | enter: rpl_parse: 0  rpl_pivot: 1
T@11   : | | | <mysql_send_query
T@11   : | | | >net_clear
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 0  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=16384
T@11   : | | | | | vio_error: Got error 90 during read
T@11   : | | | | | exit: -1
T@11   : | | | | <vio_read
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 0
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | <net_clear
T@11   : | | | >net_write_command
T@11   : | | | | enter: length: 16
T@11   : | | | <net_write_command
T@11   : | | | >net_flush
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >net_real_write
T@11   : | | | | | >thr_alarm
T@11   : | | | | | | enter: thread: T@11  sec: 60
T@11   : | | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | | <thr_alarm
T@11   : | | | | | >vio_write
T@11   : | | | | | | enter: sd=23, buf=a9735a4, size=21
T@11   : | | | | | | exit: 21
T@11   : | | | | | <vio_write
T@11   : | | | | | >thr_end_alarm
T@11   : | | | | | <thr_end_alarm
T@11   : | | | | | >vio_blocking
T@11   : | | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | | exit: 0
T@11   : | | | | | <vio_blocking
T@11   : | | | | <net_real_write
T@11   : | | | <net_flush
T@11   : | | <mysql_real_query
T@11   : | | >cli_read_query_result
T@11   : | | | >vio_is_blocking
T@11   : | | | | exit: 1
T@11   : | | | <vio_is_blocking
T@11   : | | | >thr_alarm
T@11   : | | | | enter: thread: T@11  sec: 10
T@11   : | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | <thr_alarm
T@11   : | | | >vio_read
T@11   : | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | exit: 4
T@11   : | | | <vio_read
T@11   : | | | packet_header: Memory: a9735a4  Bytes: (4)
01 00 00 01
T@11   : | | | >vio_read
T@11   : | | | | enter: sd=23, buf=a9735a4, size=1
T@11   : | | | | exit: 1
T@11   : | | | <vio_read
T@11   : | | | >thr_end_alarm
T@11   : | | | <thr_end_alarm
T@11   : | | | >vio_blocking
T@11   : | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | exit: 0
T@11   : | | | <vio_blocking
T@11   : | | | >free_old_query
T@11   : | | | | >init_alloc_root
T@11   : | | | | | enter: root: 0xa85860c
T@11   : | | | | <init_alloc_root
T@11   : | | | <free_old_query
T@11   : | | | >cli_read_rows
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
1F 00 00 02
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=31
T@11   : | | | | | exit: 31
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >my_malloc
T@11   : | | | | | my: size: 52  my_flags: 48
T@11   : | | | | | exit: ptr: 0xa8589ec
T@11   : | | | | <my_malloc
T@11   : | | | | >init_alloc_root
T@11   : | | | | | enter: root: 0xa8589fc
T@11   : | | | | <init_alloc_root
T@11   : | | | | >my_malloc
T@11   : | | | | | my: size: 8164  my_flags: 16
T@11   : | | | | | exit: ptr: 0xa9b68c0
T@11   : | | | | <my_malloc
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
1A 00 00 03
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=26
T@11   : | | | | | exit: 26
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
1A 00 00 04
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=26
T@11   : | | | | | exit: 26
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
27 00 00 05
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=39
T@11   : | | | | | exit: 39
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
1F 00 00 06
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=31
T@11   : | | | | | exit: 31
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
01 00 00 07
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=1
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | exit: Got 5 rows
T@11   : | | | <cli_read_rows
T@11   : | | | >unpack_fields
T@11   : | | | | >my_malloc
T@11   : | | | | | my: size: 8164  my_flags: 16
T@11   : | | | | | exit: ptr: 0xa9fad6c
T@11   : | | | | <my_malloc
T@11   : | | | | >free_root
T@11   : | | | | | enter: root: 0xa8589fc  flags: 0
T@11   : | | | | | >my_free
T@11   : | | | | | | my: ptr: 0xa9b68c0
T@11   : | | | | | <my_free
T@11   : | | | | <free_root
T@11   : | | | | >my_free
T@11   : | | | | | my: ptr: 0xa8589ec
T@11   : | | | | <my_free
T@11   : | | | <unpack_fields
T@11   : | | <cli_read_query_result
T@11   : | | >mysql_store_result
T@11   : | | | >my_malloc
T@11   : | | | | my: size: 104  my_flags: 48
T@11   : | | | | exit: ptr: 0xa8589ec
T@11   : | | | <my_malloc
T@11   : | | | >cli_read_rows
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
15 00 00 08
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=21
T@11   : | | | | | exit: 21
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | >my_malloc
T@11   : | | | | | my: size: 52  my_flags: 48
T@11   : | | | | | exit: ptr: 0xa858a58
T@11   : | | | | <my_malloc
T@11   : | | | | >init_alloc_root
T@11   : | | | | | enter: root: 0xa858a68
T@11   : | | | | <init_alloc_root
T@11   : | | | | >my_malloc
T@11   : | | | | | my: size: 8164  my_flags: 16
T@11   : | | | | | exit: ptr: 0xa9b68c0
T@11   : | | | | <my_malloc
T@11   : | | | | >vio_is_blocking
T@11   : | | | | | exit: 1
T@11   : | | | | <vio_is_blocking
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 10
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | | | | exit: 4
T@11   : | | | | <vio_read
T@11   : | | | | packet_header: Memory: a9735a4  Bytes: (4)
05 00 00 09
T@11   : | | | | >vio_read
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=5
T@11   : | | | | | exit: 5
T@11   : | | | | <vio_read
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | | info: status: 2  warning_count:  0
T@11   : | | | | exit: Got 1 rows
T@11   : | | | <cli_read_rows
T@11   : | | <mysql_store_result
T@11   : | | >mysql_fetch_row
T@11   : | | <mysql_fetch_row
T@11   : | | >hash_search
T@11   : | | | exit: found key at 0
T@11   : | | <hash_search
T@11   : | | >mysql_fetch_row
T@11   : | | | info: end of data
T@11   : | | <mysql_fetch_row
T@11   : | | >mysql_free_result
T@11   : | | | enter: mysql_res: a8589ec
T@11   : | | | >free_root
T@11   : | | | | enter: root: 0xa858a68  flags: 0
T@11   : | | | | >my_free
T@11   : | | | | | my: ptr: 0xa9b68c0
T@11   : | | | | <my_free
T@11   : | | | <free_root
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa858a58
T@11   : | | | <my_free
T@11   : | | | >free_root
T@11   : | | | | enter: root: 0xa858a08  flags: 0
T@11   : | | | | >my_free
T@11   : | | | | | my: ptr: 0xa9fad6c
T@11   : | | | | <my_free
T@11   : | | | <free_root
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa8589ec
T@11   : | | | <my_free
T@11   : | | <mysql_free_result
T@11   : | <update_slave_list
T@11   : | info: Starting reading binary log from master
T@11   : | >request_dump
T@11   : | | >net_clear
T@11   : | | | >vio_blocking
T@11   : | | | | enter: set_blocking_mode: 0  old_mode: 1
T@11   : | | | | exit: 0
T@11   : | | | <vio_blocking
T@11   : | | | >vio_read
T@11   : | | | | enter: sd=23, buf=a9735a4, size=16384
T@11   : | | | | vio_error: Got error 90 during read
T@11   : | | | | exit: -1
T@11   : | | | <vio_read
T@11   : | | | >vio_blocking
T@11   : | | | | enter: set_blocking_mode: 1  old_mode: 0
T@11   : | | | | exit: 0
T@11   : | | | <vio_blocking
T@11   : | | <net_clear
T@11   : | | >net_write_command
T@11   : | | | enter: length: 27
T@11   : | | <net_write_command
T@11   : | | >net_flush
T@11   : | | | >vio_is_blocking
T@11   : | | | | exit: 1
T@11   : | | | <vio_is_blocking
T@11   : | | | >net_real_write
T@11   : | | | | >thr_alarm
T@11   : | | | | | enter: thread: T@11  sec: 60
T@11   : | | | | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | | | | <thr_alarm
T@11   : | | | | >vio_write
T@11   : | | | | | enter: sd=23, buf=a9735a4, size=32
T@11   : | | | | | exit: 32
T@11   : | | | | <vio_write
T@11   : | | | | >thr_end_alarm
T@11   : | | | | <thr_end_alarm
T@11   : | | | | >vio_blocking
T@11   : | | | | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | | | | exit: 0
T@11   : | | | | <vio_blocking
T@11   : | | | <net_real_write
T@11   : | | <net_flush
T@11   : | <request_dump
T@11   : | >vio_is_blocking
T@11   : | | exit: 1
T@11   : | <vio_is_blocking
T@11   : | >thr_alarm
T@11   : | | enter: thread: T@11  sec: 10
T@11   : | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | <thr_alarm
T@11   : | >vio_read
T@11   : | | enter: sd=23, buf=a9735a4, size=4
T@11   : | | exit: 4
T@11   : | <vio_read
T@11   : | packet_header: Memory: a9735a4  Bytes: (4)
2D 00 00 01
T@11   : | >vio_read
T@11   : | | enter: sd=23, buf=a9735a4, size=45
T@11   : | | exit: 45
T@11   : | <vio_read
T@11   : | >thr_end_alarm
T@11   : | <thr_end_alarm
T@11   : | >vio_blocking
T@11   : | | enter: set_blocking_mode: 1  old_mode: 1
T@11   : | | exit: 0
T@11   : | <vio_blocking
T@11   : | info: len=45, net->read_pos[4] = 0

T@11   : | >queue_event
T@11   : | | >Rotate_log_event
T@11   : | | | >my_malloc
T@11   : | | | | my: size: 18  my_flags: 16
T@11   : | | | | exit: ptr: 0x85cbe80
T@11   : | | | <my_malloc
T@11   : | | <Rotate_log_event
T@11   : | | >process_io_rotate
T@11   : | | | info: master_log_pos: 'master-bin.000001' 768965
T@11   : | | <process_io_rotate
T@11   : | | >my_free
T@11   : | | | my: ptr: 0x85cbe80
T@11   : | | <my_free
T@11   : | | >MYSQL_LOG::appendv
T@11   : | | | info: max_size: 1073741824
T@11   : | | | >my_tell
T@11   : | | | | my: Fd: 16  MyFlags: 0
T@11   : | | | | exit: pos: 771605
T@11   : | | | <my_tell
T@11   : | | | >my_seek
T@11   : | | | | my: Fd: 16  Hpos: 0  Pos: 0  Whence: 2  MyFlags: 0
T@11   : | | | | exit: pos: 771605
T@11   : | | | <my_seek
T@11   : | | | >my_tell
T@11   : | | | | my: Fd: 16  MyFlags: 0
T@11   : | | | | exit: pos: 771605
T@11   : | | | <my_tell
T@11   : | | | >my_seek
T@11   : | | | | my: Fd: 16  Hpos: 0  Pos: 771605  Whence: 0  MyFlags: 0
T@11   : | | | <my_seek
T@11   : | | | >MYSQL_LOG::signal_update
T@11   : | | | <MYSQL_LOG::signal_update
T@11   : | | <MYSQL_LOG::appendv
T@11   : | | info: master_log_pos: 768965
T@11   : | | >harvest_bytes_written
T@11   : | | | info: counter: 771649  bytes_written: 44
T@11   : | | <harvest_bytes_written
T@11   : | <queue_event
T@11   : | >flush_master_info
T@11   : | | enter: master_pos: 768965
T@11   : | | >my_b_flush_io_cache
T@11   : | | | >my_write
T@11   : | | | | my: Fd: 16  Buffer: 0xa852bbc  Count: 44  MyFlags: 52
T@11   : | | | <my_write
T@11   : | | | >my_tell
T@11   : | | | | my: Fd: 16  MyFlags: 0
T@11   : | | | | exit: pos: 771649
T@11   : | | | <my_tell
T@11   : | | <my_b_flush_io_cache
T@11   : | | >my_b_seek
T@11   : | | | enter: pos: 0
T@11   : | | | >my_b_flush_io_cache
T@11   : | | | <my_b_flush_io_cache
T@11   : | | <my_b_seek
T@11   : | | >my_b_flush_io_cache
T@11   : | | | >my_seek
T@11   : | | | | my: Fd: 14  Hpos: 0  Pos: 0  Whence: 0  MyFlags: 0
T@11   : | | | <my_seek
T@11   : | | | >my_write
T@11   : | | | | my: Fd: 14  Buffer: 0xa86ad58  Count: 58  MyFlags: 20
T@11   : | | | <my_write
T@11   : | | <my_b_flush_io_cache
T@11   : | <flush_master_info
T@11   : | info: log_space_limit=0 log_space_total=771649 ignore_log_space_limit=1
T@11   : | >vio_is_blocking
T@11   : | | exit: 1
T@11   : | <vio_is_blocking
T@11   : | >thr_alarm
T@11   : | | enter: thread: T@11  sec: 10
T@11   : | | info: reschedule
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : <process_alarm
T@11   : | <thr_alarm
T@11   : | >vio_read
T@11   : | | enter: sd=23, buf=a9735a4, size=4
-------------------------------------------------------------------------

last bit of master.log
-------------------------------------------------------------------------
050310 22:33:00      86 Connect     root@localhost on
                     86 Query       SELECT UNIX_TIMESTAMP()
                     86 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                     86 Query       SELECT @@GLOBAL.COLLATION_SERVER
                     86 Query       SELECT @@GLOBAL.TIME_ZONE
                     86 Query       SHOW SLAVE HOSTS
                     86 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 22:33:10      87 Connect     root@localhost on
                     87 Query       SELECT UNIX_TIMESTAMP()
                     87 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                     87 Query       SELECT @@GLOBAL.COLLATION_SERVER
                     87 Query       SELECT @@GLOBAL.TIME_ZONE
                     87 Query       SHOW SLAVE HOSTS
                     87 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 22:33:20      88 Connect     root@localhost on
                     88 Query       SELECT UNIX_TIMESTAMP()
                     88 Query       SHOW VARIABLES LIKE 'SERVER_ID'
050310 22:33:30      89 Connect     root@localhost on
                     89 Query       SELECT @@GLOBAL.COLLATION_SERVER
                     89 Query       SELECT @@GLOBAL.TIME_ZONE
                     89 Query       SHOW SLAVE HOSTS
                     89 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
-------------------------------------------------------------------------

-------------------------------------------------------------------------
# tail -30 slave.log
                      1 Query       stop slave
                      1 Query       show status like 'Slave_running'
                      1 Query       reset slave
                      1 Query       drop table if exists t1,t2,t3,t4,t5,t6,t7,t8,t9
                      1 Query       start slave
                      3 Connect Out root@127.0.0.1:9306
                      1 Query       show status like 'Slave_running'
                      4 Query       create table t1 (word char(20) not null)
                      1 Query       select master_pos_wait('master-bin.000001',1569)
                      1 Query       stop slave
                      1 Query       start slave
                      5 Connect Out root@127.0.0.1:9306
050310 22:18:58       6 Query       SET PASSWORD FOR "root"@"localhost"="*F3A2A51A9B0F2BE2468926B4132313728C250DBF"
                      6 Query       SET PASSWORD FOR "root"@"localhost"=""
050310 22:18:59       1 Query       select master_pos_wait('master-bin.000001',1876)
                      6 Query       create table t3(n int)
                      6 Query       insert into t3 values(1),(2)
050310 22:19:00       1 Query       select * from t3
                      1 Query       select sum(length(word)) from t1
                      1 Query       select master_pos_wait('master-bin.000001',1927)
                      6 Query       drop table t1,t3
                      1 Query       stop slave
                      1 Query       reset slave
050310 22:19:27       1 Query       select get_lock("hold_slave",10)
                      1 Query       explain extended select get_lock("hold_slave",10)
                      1 Query       SHOW WARNINGS
                      1 Query       start slave
                      7 Connect Out root@127.0.0.1:9306
050310 22:19:28       8 Query       create table t1(n int)
                      8 Query       insert into t1 values(5000+get_lock("hold_slave",10)*0)
-------------------------------------------------------------------------
[10 Mar 2005 20:02] Boyd GERBER
an other data point on what is changing.
# l
total 238276
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     768965 Mar 10 19:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     456634 Mar 10 19:51 master.log
-rw-rw----   1 gerberb  zenez    65252364 Mar 10 19:51 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     776973 Mar 10 19:51 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       3782 Mar 10 19:29 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    39033038 Mar 10 19:51 slave.trace

# l
total 253650
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     768965 Mar 10 19:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     478054 Mar 10 20:01 master.log
-rw-rw----   1 gerberb  zenez    69362064 Mar 10 20:01 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     779569 Mar 10 20:01 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       3782 Mar 10 19:29 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    42738552 Mar 10 20:01 slave.trace
[11 Mar 2005 0:31] Boyd GERBER
These have not change now in almost 4 hours.

# l
total 309854
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     768965 Mar 10 19:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     556345 Mar 10 20:38 master.log
-rw-rw----   1 gerberb  zenez    84389420 Mar 10 20:39 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     789205 Mar 10 20:38 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       4794 Mar 10 20:39 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    56285253 Mar 10 20:39 slave.trace

# tail -100 master.trace
T@480  : | | <ha_rollback_trans
T@480  : | | >mysql_ha_flush
T@480  : | | | enter: tables: 0  mode_flags: 0x02
T@480  : | | <mysql_ha_flush
T@480  : | | >hash_free
T@480  : | | | enter: hash: 0xa9e7250d
T@480  : | | <hash_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9e8a80
T@480  : | | <my_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9eb354
T@480  : | | <my_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9e8ca4
T@480  : | | <my_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0x8645fc0
T@480  : | | <my_free
T@480  : | | >hash_free
T@480  : | | | enter: hash: 0xa9e69f4d
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0xa9e8b40
T@480  : | | | <my_free
T@480  : | | <hash_free
T@480  : | <THD::cleanup
T@480  : | >~THD()
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa8d6604
T@480  : | | <my_free
T@480  : | | >net_end
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0xaa376e0
T@480  : | | | <my_free
T@480  : | | <net_end
T@480  : | | >close_cached_file
T@480  : | | | >end_io_cache
T@480  : | | | | >my_free
T@480  : | | | | | my: ptr: 0xa9eeb10
T@480  : | | | | <my_free
T@480  : | | | <end_io_cache
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0x863eac4
T@480  : | | | <my_free
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0x85cbdf0
T@480  : | | | <my_free
T@480  : | | <close_cached_file
T@480  : | | info: freeing host
T@480  : | | >my_free
T@480  : | | | my: ptr: 0x8635990
T@480  : | | <my_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0x85d84d4
T@480  : | | <my_free
T@480  : | | >my_free
T@480  : | | | my: ptr: 0x86359a0
T@480  : | | <my_free
T@480  : | | >free_root
T@480  : | | | enter: root: 0xa9e6994  flags: 0
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0xa9e856c
T@480  : | | | <my_free
T@480  : | | <free_root
T@480  : | | >free_root
T@480  : | | | enter: root: 0xa9e73bc  flags: 0
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0xa9e7510
T@480  : | | | <my_free
T@480  : | | <free_root
T@480  : | <~THD()
T@480  : | >free_root
T@480  : | | enter: root: 0xa9e6c24  flags: 0
T@480  : | <free_root
T@480  : | >hash_free
T@480  : | | enter: hash: 0xa9e6be4d
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9eb874
T@480  : | | <my_free
T@480  : | <hash_free
T@480  : | >hash_free
T@480  : | | enter: hash: 0xa9e6bacd
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9e89c4
T@480  : | | <my_free
T@480  : | <hash_free
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9e925c
T@480  : | <my_free
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9de12c
T@480  : | <my_free
T@480  : | >free_root
T@480  : | | enter: root: 0xa9e6184  flags: 0
T@480  : | <free_root
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9e6174
T@480  : | <my_free
T@480  : | info: sending a broadcast
T@480  : | info: unlocked thread_count mutex

# tail -100 slave.trace
T@11   : | | | my: ptr: 0xa8588dc
T@11   : | | <my_free
T@11   : | | >my_free
T@11   : | | | my: ptr: 0x85d84b0
T@11   : | | <my_free
T@11   : | | >my_free
T@11   : | | | my: ptr: 0x85cbdd0
T@11   : | | <my_free
T@11   : | | >my_free
T@11   : | | | my: ptr: 0x0
T@11   : | | <my_free
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa858374
T@11   : | | <my_free
T@11   : | <mysql_close
T@11   : | >net_end
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa96f598
T@11   : | | <my_free
T@11   : | <net_end
T@11   : | >~THD()
T@11   : | | >THD::cleanup
T@11   : | | | >ha_rollback_trans
T@11   : | | | | >reinit_io_cache
T@11   : | | | | | enter: cache: 0xa857d14 type: 1  seek_offset: 0  clear_cache: 1
T@11   : | | | | <reinit_io_cache
T@11   : | | | <ha_rollback_trans
T@11   : | | | >mysql_ha_flush
T@11   : | | | | enter: tables: 0  mode_flags: 0x02
T@11   : | | | <mysql_ha_flush
T@11   : | | | >hash_free
T@11   : | | | | enter: hash: 0xa857ca0d
T@11   : | | | <hash_free
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa9f69c8
T@11   : | | | <my_free
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa85ad10
T@11   : | | | <my_free
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa85ad38
T@11   : | | | <my_free
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa85adec
T@11   : | | | <my_free
T@11   : | | | >hash_free
T@11   : | | | | enter: hash: 0xa857444d
T@11   : | | | | >my_free
T@11   : | | | | | my: ptr: 0xa85ad68
T@11   : | | | | <my_free
T@11   : | | | <hash_free
T@11   : | | <THD::cleanup
T@11   : | | >close_cached_file
T@11   : | | | >end_io_cache
T@11   : | | | | >my_free
T@11   : | | | | | my: ptr: 0xa9e8990
T@11   : | | | | <my_free
T@11   : | | | <end_io_cache
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa85ae30
T@11   : | | | <my_free
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0x85cbdd8
T@11   : | | | <my_free
T@11   : | | <close_cached_file
T@11   : | | info: freeing host
T@11   : | | >free_root
T@11   : | | | enter: root: 0xa8573e4  flags: 0
T@11   : | | | >my_free
T@11   : | | | | my: ptr: 0xa857f60
T@11   : | | | <my_free
T@11   : | | <free_root
T@11   : | | >free_root
T@11   : | | | enter: root: 0xa857e0c  flags: 0
T@11   : | | <free_root
T@11   : | <~THD()
T@11   : | >free_root
T@11   : | | enter: root: 0xa857674  flags: 0
T@11   : | <free_root
T@11   : | >hash_free
T@11   : | | enter: hash: 0xa857634d
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa9f6944
T@11   : | | <my_free
T@11   : | <hash_free
T@11   : | >hash_free
T@11   : | | enter: hash: 0xa8575fcd
T@11   : | | >my_free
T@11   : | | | my: ptr: 0xa9f68c0
T@11   : | | <my_free
T@11   : | <hash_free
T@11   : | >free_root
T@11   : | | enter: root: 0xa856bd4  flags: 0
T@11   : | <free_root
T@11   : | >my_free
T@11   : | | my: ptr: 0xa856bc4
T@11   : | <my_free
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : <process_alarm

# tail -40 master.log
050310 23:37:51     471 Connect     root@localhost on
                    471 Query       SELECT UNIX_TIMESTAMP()
                    471 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    471 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    471 Query       SELECT @@GLOBAL.TIME_ZONE
                    471 Query       SHOW SLAVE HOSTS
                    471 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 23:38:01     472 Connect     root@localhost on
                    472 Query       SELECT UNIX_TIMESTAMP()
                    472 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    472 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    472 Query       SELECT @@GLOBAL.TIME_ZONE
                    472 Query       SHOW SLAVE HOSTS
                    472 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 23:38:11     473 Connect     root@localhost on
                    473 Query       SELECT UNIX_TIMESTAMP()
                    473 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    473 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    473 Query       SELECT @@GLOBAL.TIME_ZONE
                    473 Query       SHOW SLAVE HOSTS
                    473 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 23:38:21     474 Connect     root@localhost on
                    474 Query       SELECT UNIX_TIMESTAMP()
                    474 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    474 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    474 Query       SELECT @@GLOBAL.TIME_ZONE
                    474 Query       SHOW SLAVE HOSTS
                    474 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 23:38:31     475 Connect     root@localhost on
                    475 Query       SELECT UNIX_TIMESTAMP()
                    475 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    475 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    475 Query       SELECT @@GLOBAL.TIME_ZONE
                    475 Query       SHOW SLAVE HOSTS
                    475 Binlog Dump Log: 'master-bin.000001'  Pos: 768965
050310 23:38:41     476 Connect     root@localhost on
                    476 Query       SELECT UNIX_TIMESTAMP()
                    476 Query       SHOW VARIABLES LIKE 'SERVER_ID'
                    476 Query       SELECT @@GLOBAL.COLLATION_SERVER
                    476 Query       SELECT @@GLOBAL.TIME_ZONE

# tail -40 slave.log
/home/osr5/mysql-4.1.10/sql/mysqld, Version: 4.1.10-debug-log. started with:
Tcp port: 9308  Unix socket: /home/osr5/mysql-4.1.10/mysql-test/var/tmp/slave.sock
Time                 Id Command    Argument
050310 22:18:55       1 Connect     root@localhost on test
                      2 Connect     root@localhost on test
                      1 Query       stop slave
                      1 Query       show status like 'Slave_running'
                      1 Query       reset slave
                      1 Query       drop table if exists t1,t2,t3,t4,t5,t6,t7,t8,t9
                      1 Query       start slave
                      3 Connect Out root@127.0.0.1:9306
                      1 Query       show status like 'Slave_running'
                      4 Query       create table t1 (word char(20) not null)
                      1 Query       select master_pos_wait('master-bin.000001',1569)
                      1 Query       stop slave
                      1 Query       start slave
                      5 Connect Out root@127.0.0.1:9306
050310 22:18:58       6 Query       SET PASSWORD FOR "root"@"localhost"="*F3A2A51A9B0F2BE2468926B4132313728C250DBF"
                      6 Query       SET PASSWORD FOR "root"@"localhost"=""
050310 22:18:59       1 Query       select master_pos_wait('master-bin.000001',1876)
                      6 Query       create table t3(n int)
                      6 Query       insert into t3 values(1),(2)
050310 22:19:00       1 Query       select * from t3
                      1 Query       select sum(length(word)) from t1
                      1 Query       select master_pos_wait('master-bin.000001',1927)
                      6 Query       drop table t1,t3
                      1 Query       stop slave
                      1 Query       reset slave
050310 22:19:27       1 Query       select get_lock("hold_slave",10)
                      1 Query       explain extended select get_lock("hold_slave",10)
                      1 Query       SHOW WARNINGS
                      1 Query       start slave
                      7 Connect Out root@127.0.0.1:9306
050310 22:19:28       8 Query       create table t1(n int)
                      8 Query       insert into t1 values(5000+get_lock("hold_slave",10)*0)
[11 Mar 2005 3:42] Boyd GERBER
about 3  hours later you can see the only change is in the master.trace and slave.trace files.

# date
Fri Mar 11 03:35:47 GMT 2005
# l
total 309930
-rw-r--r--   1 gerberb  zenez         10 Mar 10 19:18 current_test
-rw-rw----   1 gerberb  zenez     769029 Mar 11 03:19 master-bin.000001
-rw-rw----   1 gerberb  zenez         61 Mar 10 19:19 master-bin.index
-rw-r--r--   1 gerberb  zenez       1210 Mar 10 19:19 master.err
-rw-rw----   1 gerberb  zenez     556345 Mar 10 20:38 master.log
-rw-rw----   1 gerberb  zenez    84408946 Mar 11 03:19 master.trace
-rw-r--r--   1 gerberb  zenez          0 Mar 10 19:18 mysqltest-time
-rw-r--r--   1 gerberb  zenez    15210375 Mar 10 19:19 mysqltest.trace
-rw-rw----   1 gerberb  zenez       2055 Mar 10 19:19 slave-bin.000001
-rw-rw----   1 gerberb  zenez         60 Mar 10 19:18 slave-bin.index
-rw-rw----   1 gerberb  zenez     789205 Mar 10 20:38 slave-relay-bin.000002
-rw-rw----   1 gerberb  zenez         66 Mar 10 19:19 slave-relay-bin.index
-rw-r--r--   1 gerberb  zenez       4794 Mar 10 20:39 slave.err
-rw-rw----   1 gerberb  zenez       1910 Mar 10 19:19 slave.log
-rw-rw----   1 gerberb  zenez    56304573 Mar 11 03:35 slave.trace

# tail  -1000 master.trace
er: root: 0xa9e73bc  flags: 0
T@480  : | | | >my_free
T@480  : | | | | my: ptr: 0xa9e7510
T@480  : | | | <my_free
T@480  : | | <free_root
T@480  : | <~THD()
T@480  : | >free_root
T@480  : | | enter: root: 0xa9e6c24  flags: 0
T@480  : | <free_root
T@480  : | >hash_free
T@480  : | | enter: hash: 0xa9e6be4d
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9eb874
T@480  : | | <my_free
T@480  : | <hash_free
T@480  : | >hash_free
T@480  : | | enter: hash: 0xa9e6bacd
T@480  : | | >my_free
T@480  : | | | my: ptr: 0xa9e89c4
T@480  : | | <my_free
T@480  : | <hash_free
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9e925c
T@480  : | <my_free
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9de12c
T@480  : | <my_free
T@480  : | >free_root
T@480  : | | enter: root: 0xa9e6184  flags: 0
T@480  : | <free_root
T@480  : | >my_free
T@480  : | | my: ptr: 0xa9e6174
T@480  : | <my_free
T@480  : | info: sending a broadcast
T@480  : | info: unlocked thread_count mutex
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 3
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@7    : | | vio_error: Got error 4 during read
T@7    : | | exit: -1
T@7    : | <vio_read
T@7    : | info: vio_read returned -1,  errno: 4
T@7    : | error: Couldn't read packet: remain: 4  errno: 4  length: -1
T@7    : | >thr_end_alarm
T@7    : | <thr_end_alarm
T@7    : | >vio_blocking
T@7    : | | enter: set_blocking_mode: 0  old_mode: 1
T@7    : | | exit: 0
T@7    : | <vio_blocking
T@7    : | info: Got error 2 reading command from socket TCP/IP (17)
T@7    : <do_command
T@7    : >free_root
T@7    : | enter: root: 0xa965548  flags: 0
T@7    : | >my_free
T@7    : | | my: ptr: 0xa9a7a68
T@7    : | <my_free
T@7    : <free_root
T@7    : >send_error
T@7    : | enter: sql_errno: 1159  err: NULL
T@7    : | >query_cache_abort
T@7    : | <query_cache_abort
T@7    : | >net_write_command
T@7    : | | enter: length: 49
T@7    : | <net_write_command
T@7    : | >net_flush
T@7    : | | >vio_is_blocking
T@7    : | | | exit: 0
T@7    : | | <vio_is_blocking
T@7    : | | >net_real_write
T@7    : | | <net_real_write
T@7    : | <net_flush
T@7    : <send_error
T@7    : >close_connection
T@7    : | enter: fd: TCP/IP (17)  error: ''
T@7    : | >vio_close
T@7    : | <vio_close
T@7    : <close_connection
T@7    : >end_thread
T@7    : | >THD::cleanup
T@7    : | | >ha_rollback_trans
T@7    : | | | >reinit_io_cache
T@7    : | | | | enter: cache: 0xa966688 type: 1  seek_offset: 0  clear_cache: 1
T@7    : | | | <reinit_io_cache
T@7    : | | <ha_rollback_trans
T@7    : | | >mysql_ha_flush
T@7    : | | | enter: tables: 0  mode_flags: 0x02
T@7    : | | <mysql_ha_flush
T@7    : | | >hash_free
T@7    : | | | enter: hash: 0xa966614d
T@7    : | | <hash_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859d3c
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859d64
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859d8c
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859dbc
T@7    : | | <my_free
T@7    : | | >hash_free
T@7    : | | | enter: hash: 0xa965db8d
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0xa966ce8
T@7    : | | | <my_free
T@7    : | | <hash_free
T@7    : | <THD::cleanup
T@7    : | >~THD()
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa96eda0
T@7    : | | <my_free
T@7    : | | >net_end
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0xa96ee30
T@7    : | | | <my_free
T@7    : | | <net_end
T@7    : | | >close_cached_file
T@7    : | | | >end_io_cache
T@7    : | | | | >my_free
T@7    : | | | | | my: ptr: 0xa966d9c
T@7    : | | | | <my_free
T@7    : | | | <end_io_cache
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0xa966d6c
T@7    : | | | <my_free
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0x85cbdd0
T@7    : | | | <my_free
T@7    : | | <close_cached_file
T@7    : | | info: freeing host
T@7    : | | >my_free
T@7    : | | | my: ptr: 0x8635950
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0x85d84a4
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0x8635940
T@7    : | | <my_free
T@7    : | | >my_free
T@7    : | | | my: ptr: 0x85d84b0
T@7    : | | <my_free
T@7    : | | >free_root
T@7    : | | | enter: root: 0xa965d58  flags: 0
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0xa9668d4
T@7    : | | | <my_free
T@7    : | | <free_root
T@7    : | | >free_root
T@7    : | | | enter: root: 0xa966780  flags: 0
T@7    : | | | >my_free
T@7    : | | | | my: ptr: 0xa9a9a7c
T@7    : | | | <my_free
T@7    : | | <free_root
T@7    : | <~THD()
T@7    : | >free_root
T@7    : | | enter: root: 0xa965fe8  flags: 0
T@7    : | <free_root
T@7    : | >hash_free
T@7    : | | enter: hash: 0xa965fa8d
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859cb8
T@7    : | | <my_free
T@7    : | <hash_free
T@7    : | >hash_free
T@7    : | | enter: hash: 0xa965f70d
T@7    : | | >my_free
T@7    : | | | my: ptr: 0xa859c34
T@7    : | | <my_free
T@7    : | <hash_free
T@7    : | >my_free
T@7    : | | my: ptr: 0xa9a3a5c
T@7    : | <my_free
T@7    : | >free_root
T@7    : | | enter: root: 0xa965548  flags: 0
T@7    : | <free_root
T@7    : | >my_free
T@7    : | | my: ptr: 0xa965538
T@7    : | <my_free
T@7    : | info: sending a broadcast
T@7    : | info: unlocked thread_count mutex
T@5    : | | vio_error: Got error 4 during read
T@5    : | | exit: -1
T@5    : | <vio_read
T@5    : | info: vio_read returned -1,  errno: 4
T@5    : | error: Couldn't read packet: remain: 4  errno: 4  length: -1
T@5    : | >thr_end_alarm
T@5    : | <thr_end_alarm
T@5    : | >vio_blocking
T@5    : | | enter: set_blocking_mode: 0  old_mode: 1
T@5    : | | exit: 0
T@5    : | <vio_blocking
T@5    : | info: Got error 2 reading command from socket socket (14)
T@5    : <do_command
T@5    : >free_root
T@5    : | enter: root: 0xa868388  flags: 0
T@5    : | >my_free
T@5    : | | my: ptr: 0xa8d2ee8
T@5    : | <my_free
T@5    : <free_root
T@5    : >send_error
T@5    : | enter: sql_errno: 1159  err: NULL
T@5    : | >query_cache_abort
T@5    : | <query_cache_abort
T@5    : | >net_write_command
T@5    : | | enter: length: 49
T@5    : | <net_write_command
T@5    : | >net_flush
T@5    : | | >vio_is_blocking
T@5    : | | | exit: 0
T@5    : | | <vio_is_blocking
T@5    : | | >net_real_write
T@5    : | | <net_real_write
T@5    : | <net_flush
T@5    : <send_error
T@5    : >close_connection
T@5    : | enter: fd: socket (14)  error: ''
T@5    : | >vio_close
T@5    : | <vio_close
T@5    : <close_connection
T@5    : >end_thread
T@5    : | >THD::cleanup
T@5    : | | >ha_rollback_trans
T@5    : | | | >reinit_io_cache
T@5    : | | | | enter: cache: 0xa8694c8 type: 1  seek_offset: 0  clear_cache: 1
T@5    : | | | <reinit_io_cache
T@5    : | | <ha_rollback_trans
T@5    : | | >mysql_ha_flush
T@5    : | | | enter: tables: 0  mode_flags: 0x02
T@5    : | | <mysql_ha_flush
T@5    : | | >hash_free
T@5    : | | | enter: hash: 0xa869454d
T@5    : | | <hash_free
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa86a924
T@5    : | | <my_free
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa85a468
T@5    : | | <my_free
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa869798
T@5    : | | <my_free
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa86984c
T@5    : | | <my_free
T@5    : | | >hash_free
T@5    : | | | enter: hash: 0xa868bf8d
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0xa8697c8
T@5    : | | | <my_free
T@5    : | | <hash_free
T@5    : | <THD::cleanup
T@5    : | >~THD()
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa859f88
T@5    : | | <my_free
T@5    : | | >net_end
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0xa89ae04
T@5    : | | | <my_free
T@5    : | | <net_end
T@5    : | | >close_cached_file
T@5    : | | | >end_io_cache
T@5    : | | | | >my_free
T@5    : | | | | | my: ptr: 0xa850a70
T@5    : | | | | <my_free
T@5    : | | | <end_io_cache
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0xa869890
T@5    : | | | <my_free
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0x85cbdb0
T@5    : | | | <my_free
T@5    : | | <close_cached_file
T@5    : | | info: freeing host
T@5    : | | >my_free
T@5    : | | | my: ptr: 0x85d8480
T@5    : | | <my_free
T@5    : | | >my_free
T@5    : | | | my: ptr: 0x85d848c
T@5    : | | <my_free
T@5    : | | >free_root
T@5    : | | | enter: root: 0xa868b98  flags: 0
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0xa85aca8
T@5    : | | | <my_free
T@5    : | | <free_root
T@5    : | | >free_root
T@5    : | | | enter: root: 0xa8695c0  flags: 0
T@5    : | | | >my_free
T@5    : | | | | my: ptr: 0xa858a74
T@5    : | | | <my_free
T@5    : | | <free_root
T@5    : | <~THD()
T@5    : | >free_root
T@5    : | | enter: root: 0xa868e28  flags: 0
T@5    : | <free_root
T@5    : | >hash_free
T@5    : | | enter: hash: 0xa868de8d
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa869714
T@5    : | | <my_free
T@5    : | <hash_free
T@5    : | >hash_free
T@5    : | | enter: hash: 0xa868db0d
T@5    : | | >my_free
T@5    : | | | my: ptr: 0xa85a3e4
T@5    : | | <my_free
T@5    : | <hash_free
T@5    : | >my_free
T@5    : | | my: ptr: 0xa8ceedc
T@5    : | <my_free
T@5    : | >free_root
T@5    : | | enter: root: 0xa868388  flags: 0
T@5    : | <free_root
T@5    : | >my_free
T@5    : | | my: ptr: 0xa868378
T@5    : | <my_free
T@5    : | info: sending a broadcast
T@5    : | info: unlocked thread_count mutex
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 1
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 1
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@6    : | | vio_error: Got error 4 during read
T@6    : | | exit: -1
T@6    : | <vio_read
T@6    : | info: vio_read returned -1,  errno: 4
T@6    : | error: Couldn't read packet: remain: 4  errno: 4  length: -1
T@6    : | >thr_end_alarm
T@6    : | <thr_end_alarm
T@6    : | >vio_blocking
T@6    : | | enter: set_blocking_mode: 0  old_mode: 1
T@6    : | | exit: 0
T@6    : | <vio_blocking
T@6    : | info: Got error 2 reading command from socket TCP/IP (16)
T@6    : <do_command
T@6    : >free_root
T@6    : | enter: root: 0xa8d4f0c  flags: 0
T@6    : | >my_free
T@6    : | | my: ptr: 0xa962510
T@6    : | <my_free
T@6    : <free_root
T@6    : >send_error
T@6    : | enter: sql_errno: 1159  err: NULL
T@6    : | >query_cache_abort
T@6    : | <query_cache_abort
T@6    : | >net_write_command
T@6    : | | enter: length: 49
T@6    : | <net_write_command
T@6    : | >net_flush
T@6    : | | >vio_is_blocking
T@6    : | | | exit: 0
T@6    : | | <vio_is_blocking
T@6    : | | >net_real_write
T@6    : | | <net_real_write
T@6    : | <net_flush
T@6    : <send_error
T@6    : >close_connection
T@6    : | enter: fd: TCP/IP (16)  error: ''
T@6    : | >vio_close
T@6    : | <vio_close
T@6    : <close_connection
T@6    : >end_thread
T@6    : | >THD::cleanup
T@6    : | | >ha_rollback_trans
T@6    : | | | >reinit_io_cache
T@6    : | | | | enter: cache: 0xa8d604c type: 1  seek_offset: 0  clear_cache: 1
T@6    : | | | <reinit_io_cache
T@6    : | | <ha_rollback_trans
T@6    : | | >mysql_ha_flush
T@6    : | | | enter: tables: 0  mode_flags: 0x02
T@6    : | | <mysql_ha_flush
T@6    : | | >hash_free
T@6    : | | | enter: hash: 0xa8d5fd8d
T@6    : | | <hash_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa85bc20
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa85bc48
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa85a120
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa859a88
T@6    : | | <my_free
T@6    : | | >hash_free
T@6    : | | | enter: hash: 0xa8d577cd
T@6    : | | | >my_free
T@6    : | | | | my: ptr: 0xa85a150
T@6    : | | | <my_free
T@6    : | | <hash_free
T@6    : | | >MYSQL_LOG::write(event)
T@6    : | | | info: event type=2
T@6    : | | | >my_b_flush_io_cache
T@6    : | | | | >my_write
T@6    : | | | | | my: Fd: 7  Buffer: 0xa9aaac4  Count: 64  MyFlags: 52
T@6    : | | | | <my_write
T@6    : | | | <my_b_flush_io_cache
T@6    : | | | info: max_size: 1073741824
T@6    : | | | >MYSQL_LOG::signal_update
T@6    : | | | <MYSQL_LOG::signal_update
T@6    : | | <MYSQL_LOG::write(event)
T@6    : | | >hash_delete
T@6    : | | <hash_delete
T@6    : | | >my_free
T@6    : | | | my: ptr: 0x8635980
T@6    : | | <my_free
T@6    : | <THD::cleanup
T@6    : | >~THD()
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa859acc
T@6    : | | <my_free
T@6    : | | >net_end
T@6    : | | | >my_free
T@6    : | | | | my: ptr: 0xa8de6b0
T@6    : | | | <my_free
T@6    : | | <net_end
T@6    : | | >close_cached_file
T@6    : | | | >end_io_cache
T@6    : | | | | >my_free
T@6    : | | | | | my: ptr: 0xa8d66ac
T@6    : | | | | <my_free
T@6    : | | | <end_io_cache
T@6    : | | | >my_free
T@6    : | | | | my: ptr: 0xa85a1d4
T@6    : | | | <my_free
T@6    : | | | >my_free
T@6    : | | | | my: ptr: 0x85cbdb8
T@6    : | | | <my_free
T@6    : | | <close_cached_file
T@6    : | | info: freeing host
T@6    : | | >my_free
T@6    : | | | my: ptr: 0x8635930
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0x85d8474
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0x8635920
T@6    : | | <my_free
T@6    : | | >my_free
T@6    : | | | my: ptr: 0x85d8498
T@6    : | | <my_free
T@6    : | | >free_root
T@6    : | | | enter: root: 0xa8d571c  flags: 0
T@6    : | | <free_root
T@6    : | | >free_root
T@6    : | | | enter: root: 0xa8d6144  flags: 0
T@6    : | | | >my_free
T@6    : | | | | my: ptr: 0xa964524
T@6    : | | | <my_free
T@6    : | | <free_root
T@6    : | <~THD()
T@6    : | >free_root
T@6    : | | enter: root: 0xa8d59ac  flags: 0
T@6    : | <free_root
T@6    : | >hash_free
T@6    : | | enter: hash: 0xa8d596cd
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa85a09c
T@6    : | | <my_free
T@6    : | <hash_free
T@6    : | >hash_free
T@6    : | | enter: hash: 0xa8d5934d
T@6    : | | >my_free
T@6    : | | | my: ptr: 0xa85a018
T@6    : | | <my_free
T@6    : | <hash_free
T@6    : | >my_free
T@6    : | | my: ptr: 0x8645d10
T@6    : | <my_free
T@6    : | >my_free
T@6    : | | my: ptr: 0xa95e504
T@6    : | <my_free
T@6    : | >free_root
T@6    : | | enter: root: 0xa8d4f0c  flags: 0
T@6    : | <free_root
T@6    : | >my_free
T@6    : | | my: ptr: 0xa8d4efc
T@6    : | <my_free
T@6    : | info: sending a broadcast
T@6    : | info: unlocked thread_count mutex
T@479  : | | <wait_for_update
T@479  : | | wait: binary log received update
T@479  : | | >read_log_event
T@479  : | | | >_my_b_read
T@479  : | | | | >my_read
T@479  : | | | | | my: Fd: 19  Buffer: 0xaa3b6ec  Count: 5179  MyFlags: 16
T@479  : | | | | | warning: Read only 64 bytes off 5179 from 19, errno: -1
T@479  : | | | | <my_read
T@479  : | | | <_my_b_read
T@479  : | | | >my_malloc
T@479  : | | | | my: size: 24  my_flags: 16
T@479  : | | | | exit: ptr: 0x8645d10
T@479  : | | | <my_malloc
T@479  : | | | >my_realloc
T@479  : | | | | my: ptr: 0x8645d10  size: 72  my_flags: 16
T@479  : | | | | exit: ptr: 0xa9ad5c4
T@479  : | | | <my_realloc
T@479  : | | <read_log_event
T@479  : | | packet_header: Memory: a9dd680  Bytes: (4)
41 00 00 02
T@479  : | | info: log event code 2
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9ad5c4
T@479  : | | <my_free
T@479  : | | >read_log_event
T@479  : | | | >_my_b_read
T@479  : | | | | >my_read
T@479  : | | | | | my: Fd: 19  Buffer: 0xaa3b6ec  Count: 5115  MyFlags: 16
T@479  : | | | | | warning: Read only 0 bytes off 5115 from 19, errno: -1
T@479  : | | | | <my_read
T@479  : | | | <_my_b_read
T@479  : | | | error: file->error: 0
T@479  : | | <read_log_event
T@479  : | | >net_flush
T@479  : | | | >vio_is_blocking
T@479  : | | | | exit: 0
T@479  : | | | <vio_is_blocking
T@479  : | | | >net_real_write
T@479  : | | | | >vio_write
T@479  : | | | | | enter: sd=18, buf=aa2ec08, size=69
T@479  : | | | | | vio_error: Got error on write: 32
T@479  : | | | | | exit: -1
T@479  : | | | | <vio_write
T@479  : | | | <net_real_write
T@479  : | | <net_flush
T@479  : | | >end_io_cache
T@479  : | | | >my_b_flush_io_cache
T@479  : | | | <my_b_flush_io_cache
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xaa3b6ec
T@479  : | | | <my_free
T@479  : | | <end_io_cache
T@479  : | | >my_close
T@479  : | | | my: fd: 19  MyFlags: 16
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xa9e8c58
T@479  : | | | <my_free
T@479  : | | <my_close
T@479  : | | >send_error
T@479  : | | | enter: sql_errno: 1105  err: failed on net_flush()
T@479  : | | | >query_cache_abort
T@479  : | | | <query_cache_abort
T@479  : | | | >net_write_command
T@479  : | | | | enter: length: 29
T@479  : | | | <net_write_command
T@479  : | | | >net_flush
T@479  : | | | | >vio_is_blocking
T@479  : | | | | | exit: 0
T@479  : | | | | <vio_is_blocking
T@479  : | | | | >net_real_write
T@479  : | | | | <net_real_write
T@479  : | | | <net_flush
T@479  : | | <send_error
T@479  : | <mysql_binlog_send
T@479  : | >hash_search
T@479  : | | exit: found key at 0
T@479  : | <hash_search
T@479  : | >free_root
T@479  : | | enter: root: 0x8644040  flags: 1
T@479  : | <free_root
T@479  : <dispatch_command
T@479  : >free_root
T@479  : | enter: root: 0x8644040  flags: 0
T@479  : | >my_free
T@479  : | | my: ptr: 0xa9e4160
T@479  : | <my_free
T@479  : <free_root
T@479  : >close_connection
T@479  : | enter: fd: TCP/IP (18)  error: ''
T@479  : | >vio_close
T@479  : | | vio_error: close() failed, error: 111
T@479  : | <vio_close
T@479  : <close_connection
T@479  : >end_thread
T@479  : | >THD::cleanup
T@479  : | | >ha_rollback_trans
T@479  : | | | >reinit_io_cache
T@479  : | | | | enter: cache: 0x8645180 type: 1  seek_offset: 0  clear_cache: 1
T@479  : | | | <reinit_io_cache
T@479  : | | <ha_rollback_trans
T@479  : | | >mysql_ha_flush
T@479  : | | | enter: tables: 0  mode_flags: 0x02
T@479  : | | <mysql_ha_flush
T@479  : | | >hash_free
T@479  : | | | enter: hash: 0x864510cd
T@479  : | | <hash_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0x8646004
T@479  : | | <my_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9e8cd8
T@479  : | | <my_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9aaa90
T@479  : | | <my_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9e8980
T@479  : | | <my_free
T@479  : | | >hash_free
T@479  : | | | enter: hash: 0x86448b0d
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xa8d62d8
T@479  : | | | <my_free
T@479  : | | <hash_free
T@479  : | <THD::cleanup
T@479  : | >~THD()
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9e8ab0
T@479  : | | <my_free
T@479  : | | >net_end
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xaa2ec08
T@479  : | | | <my_free
T@479  : | | <net_end
T@479  : | | >close_cached_file
T@479  : | | | >end_io_cache
T@479  : | | | | >my_free
T@479  : | | | | | my: ptr: 0xaa26c04
T@479  : | | | | <my_free
T@479  : | | | <end_io_cache
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xa9e8a4c
T@479  : | | | <my_free
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0x85cbdd8
T@479  : | | | <my_free
T@479  : | | <close_cached_file
T@479  : | | info: freeing host
T@479  : | | >my_free
T@479  : | | | my: ptr: 0x8635960
T@479  : | | <my_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0x85d84bc
T@479  : | | <my_free
T@479  : | | >my_free
T@479  : | | | my: ptr: 0x8635970
T@479  : | | <my_free
T@479  : | | >free_root
T@479  : | | | enter: root: 0x8644850  flags: 0
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xa9e8de4
T@479  : | | | <my_free
T@479  : | | <free_root
T@479  : | | >free_root
T@479  : | | | enter: root: 0x8645278  flags: 0
T@479  : | | | >my_free
T@479  : | | | | my: ptr: 0xa9e2138
T@479  : | | | <my_free
T@479  : | | <free_root
T@479  : | <~THD()
T@479  : | >free_root
T@479  : | | enter: root: 0x8644ae0  flags: 0
T@479  : | <free_root
T@479  : | >hash_free
T@479  : | | enter: hash: 0x8644aa0d
T@479  : | | >my_free
T@479  : | | | my: ptr: 0xa9eb7ec
T@479  : | | <my_free
T@479  : | <hash_free
T@479  : | >hash_free
T@479  : | | enter: hash: 0x8644a68d
T@479  : | | >my_free
T@479  : | | | my: ptr: 0x8645f3c
T@479  : | | <my_free
T@479  : | <hash_free
T@479  : | >my_free
T@479  : | | my: ptr: 0xa9e9238
T@479  : | <my_free
T@479  : | >free_root
T@479  : | | enter: root: 0x8644040  flags: 0
T@479  : | <free_root
T@479  : | >my_free
T@479  : | | my: ptr: 0x8644030
T@479  : | <my_free
T@479  : | info: sending a broadcast
T@479  : | info: unlocked thread_count mutex
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 0
T@3    : <process_alarm

# tail -1000 slave.trace
sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
T@3    : >process_alarm
T@3    : | info: sig: 0  active alarms: 2
T@3    : | info: sending signal to waiting thread
T@3    : | info: sending signal to waiting thread
T@3    : <process_alarm
[11 Mar 2005 3:46] Boyd GERBER
A ps on the processes show

Starting Tests

TEST                            RESULT
-------------------------------------------------------

$ ps
  PID     TTY        TIME CMD
16055   ttyp1    00:00:44 mysqld
16080   ttyp1    08:22:30 mysqld
[11 Mar 2005 14:11] MySQL Verification Team
Seems that problem happens in bootstrap thread, namely in the function:

extern "C" pthread_handler_decl(handle_bootstrap,arg)

Most specifically at it's end :

#ifndef EMBEDDED_LIBRARY
  (void) pthread_mutex_lock(&LOCK_thread_count);
  thread_count--;
  (void) pthread_mutex_unlock(&LOCK_thread_count);
  (void) pthread_cond_broadcast(&COND_thread_count);
  my_thread_end();
  pthread_exit(0);
#endif

This is due to a bug in condition broadcasting in FSU.

Try to put call to pthread_cond_broadcast in conditional compilation, so that it is not used in SCO and tell me how it goes.

This is, however, evidently a bug in FSU.
[13 Mar 2005 4:18] Boyd GERBER
When run with this in gdb everything works and no extended time.

$ ./mysql-test-run --gdb --client-gdb --debug rpl000001
Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/master-data --skip-innodb --skip-ndbcluster --skip-bdb--language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Installing Slave Databases
running  ../sql/mysqld --no-defaults --bootstrap --skip-grant-tables     --basedir=. --datadir=./var/slave-data --skip-innodb --skip-ndbcluster --skip-bdb     --language=../sql/share/english/ --character-sets-dir=../sql/share/charsets/
Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

TEST                            RESULT
-------------------------------------------------------
Set breakpoints ( if needed) and type 'run' in gdb window
rpl000001                      [ pass ]
-------------------------------------------------------

All 1 tests were successful.
[7 Apr 2005 15:44] Boyd GERBER
What feedback is required?
[7 May 2005 23:00] Bugs System
No feedback was provided for this bug for over a month, so it is
being suspended automatically. If you are able to provide the
information that was originally requested, please do so and change
the status of the bug back to "Open".
[5 Sep 2005 12:43] Valeriy Kravchuk
Hi Boyd,

I am trying to build 4.1.14 on our openserv box according to http://dev.mysql.com/doc/mysql/en/sco.html. I had installed ftp://ftp.zenez.com/pub/zenez/prgms/FSU-threads-3.14.tar.gz into subdirectory of my home directory (I have no root permissions on the machine), and tried the following configuration:

CC=gcc CXX=gcc CFLAGS="-D_XOPEN_XPG4 -I/home/mysqldev/valeriy/FSU-threads-3.14/include" CXXFLAGS="-D_XOPEN_XPG4" ./configure --prefix=/home/mysqldev/valeriy/4.1.14 --with-debug --with-named-thread-libs="-lgthreads -lsocket -lgen -lgthreads"

But later make gives me the following error messages:

gcc -DHAVE_CONFIG_H -I. -I. -I.. -I../include -I../include    -g -O -DDBUG_ON -D
SAFE_MUTEX -D_XOPEN_XPG4 -I/home/mysqldev/valeriy/FSU-threads-3.14/include   -DS
CO -c strxmov.c
In file included from /home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:
49,
                 from ../include/my_global.h:149,
                 from strxmov.c:33:
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread/signal.h:104: redefiniti
on of `struct timespec'
In file included from ../include/my_global.h:149,
                 from strxmov.c:33:
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:189: conflicting types
 for `pthread_mutex_t'
/usr/include/sys/types.h:432: previous declaration of `pthread_mutex_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:197: conflicting types
 for `pthread_mutexattr_t'
/usr/include/sys/types.h:386: previous declaration of `pthread_mutexattr_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:252: conflicting types
 for `pthread_once_t'
/usr/include/sys/types.h:500: previous declaration of `pthread_once_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:264: conflicting types
 for `pthread_cond_t'
/usr/include/sys/types.h:460: previous declaration of `pthread_cond_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:268: conflicting types
 for `pthread_condattr_t'
/usr/include/sys/types.h:380: previous declaration of `pthread_condattr_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:322: conflicting types
 for `pthread_attr_t'
/usr/include/sys/types.h:375: previous declaration of `pthread_attr_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:332: conflicting types
 for `pthread_key_t'
/usr/include/sys/types.h:493: previous declaration of `pthread_key_t'
/home/mysqldev/valeriy/FSU-threads-3.14/include/pthread.h:398: conflicting types
 for `pthread_t'
/usr/include/sys/types.h:247: previous declaration of `pthread_t'
make[2]: *** [strxmov.o] Error 1
make[2]: Leaving directory `/home/mysqldev/valeriy/mysql-4.1.14/strings'
make[1]: *** [all-recursive] Error 1
make[1]: Leaving directory `/home/mysqldev/valeriy/mysql-4.1.14'
make: *** [all] Error 2

What shell I try to do to resolve this conflicts? Will "make install" for the FSU library performed as root really help?

mysqldev@openserv:~/valeriy/mysql-4.1.14> uname -a
SCO_SV openserv 3.2 5.0.7 i386
[5 Sep 2005 15:36] Boyd GERBER
You need to modify some systems headers.  You will find what you need on

http://www.zenez.com/B/general/mysql.html

Also you will need someone to install FSU-threads in the System areas.  MySQL looks for FSU-threads in a specific location.
[5 Sep 2005 17:33] Boyd GERBER
You may want to get

ftp://ftp.zenez.com/pub/zenez/prgms/headers-files-osr5-rr.tar.gz and
ftp://ftp.zenez.com/pub/zenez/prgms/README.include
[16 Sep 2005 12:18] Valeriy Kravchuk
I was unable to repeat with today's 4.1.15 nightly sources on Open Server 6.0.0. I configured it as follows:

CC=cc CFLAGS="-I/opt/K/SCO/Unix/6.0.0Ni/usr/include -I/usr/local/include" CXX=CC \
CXXFLAGS="-I/opt/K/SCO/Unix/6.0.0Ni/usr/include -I/usr/local/include" ./configure --prefix=/home/mysqldev/valeriy/4.1 \
--with-comment="MySQL Pro (GPL)" --with-extra-charsets=complex \
--with-server-suffix="-pro-gpl" --enable-thread-safe-client \
--enable-local-infile --build=i686-unknown-sysv5SCO_SV6.0.0 \
--disable-shared --with-readline --with-archive-storage-engine \
--with-innodb --with-debug

Then, after successfull make and make install:

bash-2.03$ pwd
/home/mysqldev/valeriy/4.1
bash-2.03$ cd mysql-test/
bash-2.03$ ./mysql-test-run rollback
Installing Test Databases
Removing Stale Files
Installing Master Databases
running  ../libexec/mysqld --no-defaults --bootstrap --skip-grant-tables     --b
asedir=.. --datadir=mysql-test/var/master-data --skip-innodb --skip-ndbcluster -
-skip-bdb
Installing Slave Databases
running  ../libexec/mysqld --no-defaults --bootstrap --skip-grant-tables     --b
asedir=.. --datadir=mysql-test/var/slave-data --skip-innodb --skip-ndbcluster --
skip-bdb
Manager disabled, skipping manager start.
Loading Standard Test Databases
Starting Tests

TEST                            RESULT
-------------------------------------------------------
rollback                       [ pass ]
-------------------------------------------------------

Ending Tests
Shutting-down MySQL daemon

Master shutdown finished
Slave shutdown finished
All 1 tests were successful.

bash-2.03$ uname -a
SCO_SV opnsrv6a 5 6.0.0 i386

It was performed really fast, in a matter of seconds.

bash-2.03$ bin/mysql -uroot
Welcome to the MySQL monitor.  Commands end with ; or \g.
Your MySQL connection id is 7 to server version: 4.1.15-nightly-20050916-pro-gpl-debug

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

mysql>

I'd recommend you to try latest available 4.1.15 sources or just wait for official 4.1.15 release. I even compiles without your patches explicitely applied on SCO now!
[16 Sep 2005 15:27] Boyd GERBER
This only happens on OpenServer 5.0.7 and older 5.0.X.  It works on all other SCO OS's.
[16 Sep 2005 16:24] Boyd GERBER
From Brian's commit this should be marked will not fix.  As Only OpenServer 6.0.0 and newer or UnixWare 7.1.4 or newer will be supported.
[16 Sep 2005 16:27] Boyd GERBER
BTW, I have had over 1000 people send me email on the problem.  SCO called me to discuss the problem as well.  But as this is OpenServer  5.0.7 and older it should be marked as will not fix.  I know that someone about 1 year ago verified it on one of your customer's machines as well.