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: | |
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
[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.