MySQL Forums
Forum List  »  Performance

Re: Server Stopped Responding When Having Around 1000 Request
Posted by: hemant yadav
Date: November 22, 2012 06:49AM

Hey Rick,
Following is the error log of the server i manage to get


---TRANSACTION 0 865024556, ACTIVE 982 sec, OS thread id 2008 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 436004, query id 3809729 myhost.com smilemd_live Sending data
Select * from DTBL_Hospital
Trx read view will not see trx with id >= 0 865024557, sees < 0 865022296
---TRANSACTION 0 865024553, ACTIVE 986 sec, OS thread id 4336 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435999, query id myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1095000,100
Trx read view will not see trx with id >= 0 865024554, sees < 0 865022296
---TRANSACTION 0 865024552, ACTIVE 987 sec, OS thread id 864 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435998, query id 3809693 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094900,100
Trx read view will not see trx with id >= 0 865024553, sees < 0 865022296
---TRANSACTION 0 865024541, ACTIVE 1045 sec, OS thread id 4756 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435983, query id 3809547 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094800,100
Trx read view will not see trx with id >= 0 865024542, sees < 0 865022296
---TRANSACTION 0 865024537, ACTIVE 1049 sec, OS thread id 4988 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435971, query id 3809472 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094600,100
Trx read view will not see trx with id >= 0 865024538, sees < 0 865022296
---TRANSACTION 0 865024533, ACTIVE 1050 sec, OS thread id 2196 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435975, query id 3809473 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094700,100
Trx read view will not see trx with id >= 0 865024534, sees < 0 865022296
---TRANSACTION 0 865024527, ACTIVE 1092 sec, OS thread id 4720 waiting in InnoDB queue
mysql tables in use 3, locked 0
MySQL thread id 435967, query id 3809393 myhost.com smilemd_live Sending data
Select DTBL_Doctors.Doctor_ID, Email_ID, convert(AES_Decrypt(Password, NAME_CONST('MySqlEncryptionKey',_latin1'?payal1' COLLATE 'latin1_swedish_ci')),char)as Password,Title,
First_Name,
Middle_Name,
Last_Name,
Street_Address,
State,
City,
County,
Status,
ZipCode,
Country,
Day_Phone,
Extension,
Category,
Specilization,
Website, Video, Photo, Code, Register_IP, Date, Latitude, Longitude, Email_Verified,
BStreet_Address,
BState,
BCity,
BCounty,
BZipCode,
BDayPhone,
BStreet_Address1,
BState1,
BCity1,
BCounty1,
BZipCode2,
BDayPhone1,
Visit_Reason,
Lic_State,
State_lice
Trx read view will not see trx with id >= 0 865024528, sees < 0 865022296
---TRANSACTION 0 865024525, ACTIVE 1094 sec, OS thread id 5012 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435965, query id 3809375 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094500,100
Trx read view will not see trx with id >= 0 865024526, sees < 0 865022296
---TRANSACTION 0 865024524, ACTIVE 1094 sec, OS thread id 3660 starting index read, thread declared inside InnoDB 499
mysql tables in use 3, locked 0
MySQL thread id 435874, query id 3808631 myhost.com smilemd_live Sending data
Select DTBL_Doctors.Doctor_ID, Email_ID, convert(AES_Decrypt(Password, NAME_CONST('MySqlEncryptionKey',_latin1'?payal1' COLLATE 'latin1_swedish_ci')),char)as Password,Title,
First_Name,
Middle_Name,
Last_Name,
Street_Address,
State,
City,
County,
Status,
ZipCode,
Country,
Day_Phone,
Extension,
Category,
Specilization,
Website, Video, Photo, Code, Register_IP, Date, Latitude, Longitude, Email_Verified,
BStreet_Address,
BState,
BCity,
BCounty,
BZipCode,
BDayPhone,
BStreet_Address1,
BState1,
BCity1,
BCounty1,
BZipCode2,
BDayPhone1,
Visit_Reason,
Lic_State,
State_lice
Trx read view will not see trx with id >= 0 865024525, sees < 0 865022296
---TRANSACTION 0 865024449, ACTIVE 1142 sec, OS thread id 4776 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435857, query id 3808461 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 861500,100
Trx read view will not see trx with id >= 0 865024450, sees < 0 865022296
---TRANSACTION 0 865024440, ACTIVE 1169 sec, OS thread id 4560 waiting in InnoDB queue
mysql tables in use 1, locked 0
MySQL thread id 435848, query id 3808374 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1003900,100
Trx read view will not see trx with id >= 0 865024441, sees < 0 865022296
---TRANSACTION 0 865024436, ACTIVE 1169 sec, OS thread id 4540, thread declared inside InnoDB 395
mysql tables in use 1, locked 0
MySQL thread id 435845, query id 3808332 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094400,100
Trx read view will not see trx with id >= 0 865024437, sees < 0 865022296
---TRANSACTION 0 865024164, ACTIVE 1242 sec, OS thread id 4784, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435523, query id 3805512 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094300,100
Trx read view will not see trx with id >= 0 865024165, sees < 0 865022296
---TRANSACTION 0 865024144, ACTIVE 1246 sec, OS thread id 3220, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435489, query id 3805230 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1094100,100
Trx read view will not see trx with id >= 0 865024145, sees < 0 865022296
---TRANSACTION 0 865023998, ACTIVE 1281 sec, OS thread id 2324, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435292, query id 3803583 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093900,100
Trx read view will not see trx with id >= 0 865023999, sees < 0 865022296
---TRANSACTION 0 865023938, ACTIVE 1285 sec, OS thread id 4428, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435228, query id 3803022 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093800,100
Trx read view will not see trx with id >= 0 865023939, sees < 0 865022296
---TRANSACTION 0 865023924, ACTIVE 1292 sec, OS thread id 4632, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435201, query id 3802841 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093700,100
Trx read view will not see trx with id >= 0 865023925, sees < 0 865022296
---TRANSACTION 0 865023833, ACTIVE 1346 sec, OS thread id 4436, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435107, query id 3801898 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093600,100
Trx read view will not see trx with id >= 0 865023834, sees < 0 865022296
---TRANSACTION 0 865023758, ACTIVE 1349 sec, OS thread id 2332, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 435017, query id 3801122 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093500,100
Trx read view will not see trx with id >= 0 865023759, sees < 0 865022296
---TRANSACTION 0 865023728, ACTIVE 1349 sec, OS thread id 2904, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 434977, query id 3800795 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093400,100
Trx read view will not see trx with id >= 0 865023729, sees < 0 865022296
---TRANSACTION 0 865023712, ACTIVE 1350 sec, OS thread id 3796, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 434960, query id 3800637 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093300,100
Trx read view will not see trx with id >= 0 865023713, sees < 0 865022296
---TRANSACTION 0 865023710, ACTIVE 1350 sec, OS thread id 3212, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 434956, query id 3800599 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093200,100
Trx read view will not see trx with id >= 0 865023711, sees < 0 865022296
---TRANSACTION 0 865022770, ACTIVE 1490 sec, OS thread id 2480, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433821, query id 3790653 myhost.com smilemd_live Sending data
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 458400,100
Trx read view will not see trx with id >= 0 865022771, sees < 0 865022296
---TRANSACTION 0 865022628, ACTIVE 1506 sec, OS thread id 2444, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433654, query id 3789184 myhost.com smilemd_live Sending data
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 458400,100
Trx read view will not see trx with id >= 0 865022629, sees < 0 865022296
---TRANSACTION 0 865022617, ACTIVE 1510 sec, OS thread id 2336, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433640, query id 3789066 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1093000,100
Trx read view will not see trx with id >= 0 865022618, sees < 0 865022296
---TRANSACTION 0 865022534, ACTIVE 1512 sec, OS thread id 3648, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433533, query id 3788165 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092900,100
Trx read view will not see trx with id >= 0 865022535, sees < 0 865022296
---TRANSACTION 0 865022389, ACTIVE 1527 sec, OS thread id 4044, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433360, query id 3786650 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 932700,100
Trx read view will not see trx with id >= 0 865022390, sees < 0 865022296
---TRANSACTION 0 865022363, ACTIVE 1528 sec, OS thread id 3236, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433326, query id 3786361 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092600,100
Trx read view will not see trx with id >= 0 865022364, sees < 0 865022296
---TRANSACTION 0 865022307, ACTIVE 1530 sec, OS thread id 2776, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433273, query id 3785854 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092400,100
Trx read view will not see trx with id >= 0 865022308, sees < 0 865022296
---TRANSACTION 0 865022298, ACTIVE 1530 sec, OS thread id 2384, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433266, query id 3785784 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092300,100
Trx read view will not see trx with id >= 0 865022299, sees < 0 865022296
---TRANSACTION 0 865022297, ACTIVE 1531 sec, OS thread id 3168, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433262, query id 3785752 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092100,100
Trx read view will not see trx with id >= 0 865022298, sees < 0 865022296
---TRANSACTION 0 865022296, ACTIVE 1531 sec, OS thread id 2328, thread declared inside InnoDB 433
mysql tables in use 1, locked 0
MySQL thread id 433258, query id 3785718 myhost.com smilemd_live Sorting result
Select ProfileURL,Legal_PracticeName From DTBL_Doctors Order By Legal_PracticeName Asc Limit 1092200,100
Trx read view will not see trx with id >= 0 865022297, sees < 0 865022293
--------
FILE I/O
--------
I/O thread 0 state: wait Windows aio (insert buffer thread)
I/O thread 1 state: wait Windows aio (log thread)
I/O thread 2 state: wait Windows aio (read thread)
I/O thread 3 state: wait Windows aio (write thread)
Pending normal aio reads: 0, aio writes: 0,
ibuf aio reads: 0, log i/o's: 0, sync i/o's: 0
Pending flushes (fsync) log: 0; buffer pool: 0
222901 OS file reads, 10476 OS file writes, 6984 OS fsyncs
0.60 reads/s, 16384 avg bytes/read, 0.00 writes/s, 0.00 fsyncs/s
-------------------------------------
INSERT BUFFER AND ADAPTIVE HASH INDEX
-------------------------------------
Ibuf: size 1, free list len 2795, seg size 2797,
0 inserts, 0 merged recs, 0 merges
Hash table size 1173617, node heap has 3338 buffer(s)
0.06 hash searches/s, 13.81 non-hash searches/s
---
LOG
---
Log sequence number 11 2775514934
Log flushed up to 11 2775514924
Last checkpoint at 11 2775514924
0 pending log writes, 0 pending chkp writes
4020 log i/o's done, 0.00 log i/o's/second
----------------------
BUFFER POOL AND MEMORY
----------------------
Total memory allocated 603441320; in additional pool allocated 11522304
Dictionary memory allocated 622968
Buffer pool size 32512
Free buffers 0
Database pages 29165
Modified db pages 1
Pending reads 0
Pending writes: LRU 0, flush list 0, single page 0
Pages read 222891, created 3, written 4976
0.60 reads/s, 0.00 creates/s, 0.00 writes/s
Buffer pool hit rate 983 / 1000
--------------
ROW OPERATIONS
--------------
34 queries inside InnoDB, 182 queries in queue
131 read views open inside InnoDB
Main thread id 2252, state: purging
Number of rows inserted 3896, updated 1492, deleted 3455, read 764588435
0.00 inserts/s, 0.00 updates/s, 0.00 deletes/s, 24.25 reads/s
----------------------------
END OF INNODB MONITOR OUTPUT
============================
InnoDB: ###### Diagnostic info printed to the standard error stream
InnoDB: Error: semaphore wait has lasted > 600 seconds
InnoDB: We intentionally crash the server, because it appears to be hung.
121121 15:09:19 InnoDB: Assertion failure in thread 2240 in file .\srv\srv0srv.c line 2137
InnoDB: We intentionally generate a memory trap.
InnoDB: Submit a detailed bug report to http://bugs.mysql.com.
InnoDB: If you get repeated assertion failures or crashes, even
InnoDB: immediately after the mysqld startup, there may be
InnoDB: corruption in the InnoDB tablespace. Please refer to
InnoDB: http://dev.mysql.com/doc/refman/5.1/en/forcing-recovery.html
InnoDB: about forcing recovery.
InnoDB: Thread 3660 stopped in file .\ha\ha0ha.c line 176
InnoDB: Thread 2236 stopped in file G:\mysql-5.1.41-winbuild\mysql-community-nt-5.1.41-build\storage\innobase\include\sync0sync.ic line 115
121121 15:09:20 - mysqld got exception 0xc0000005 ;
This could be because you hit a bug. It is also possible that this binary
or one of the libraries it was linked against is corrupt, improperly built,
or misconfigured. This error can also be caused by malfunctioning hardware.
We will try our best to scrape up some info that will hopefully help diagnose
the problem, but since we have already crashed, something is definitely wrong
and this may fail.

key_buffer_size=1073741824
read_buffer_size=65536
max_used_connections=220
max_threads=10000
threads_connected=219
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 1705616 K
bytes of memory
Hope that's ok; if not, decrease some variables in the equation.

thd: 0x0
Attempting backtrace. You can use the following information to find out
where mysqld died. If you see no messages after this, something went
terribly wrong...
InnoDB: Thread 4564 stopped in file G:\mysql-5.1.41-winbuild\mysql-community-nt-5.1.41-build\storage\innobase\include\sync0sync.ic line 115
InnoDB: Thread 3648 stopped in file .\handler\ha_innodb.cc line 4700
00000001402162BE mysqld.exe!srv_error_monitor_thread()[srv0srv.c:2137]
00000000774FBE3D kernel32.dll!BaseThreadInitThunk()
0000000077BC6861 ntdll.dll!RtlUserThreadStart()
The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
information that should help you find out what is causing the crash.
InnoDB: Thread 3280 stopped in file .\handler\ha_innodb.cc line 1389
InnoDB: Thread 4428 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 2336 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 4632 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 2324 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 2480 stopped in file .\os\os0sync.c line 235
InnoDB: Thread 2444 stopped in file .\os\os0sync.c line 235
InnoDB: Thread 2776 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 4044 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 3220 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 4784 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 3796 stopped in file .\handler\ha_innodb.cc line 4700
InnoDB: Thread 2384 stopped in file .\handler\ha_innodb.cc line 4700
121122 4:17:40 [Note] Plugin 'FEDERATED' is disabled.
InnoDB: The log sequence number in ibdata files does not match
InnoDB: the log sequence number in the ib_logfiles!
121122 4:17:41 InnoDB: Database was not shut down normally!
InnoDB: Starting crash recovery.
InnoDB: Reading tablespace information from the .ibd files...
InnoDB: Restoring possible half-written data pages from the doublewrite
InnoDB: buffer...
121122 4:17:42 InnoDB: Started; log sequence number 11 2775514924
121122 4:17:43 [Note] Event Scheduler: Loaded 0 events
121122 4:17:43 [Note] C:\Program Files\MySQL\MySQL Server 5.1\bin\mysqld: ready for connections.
Version: '5.1.41-community' socket: '' port: 3306 MySQL Community Server (GPL)

Options: ReplyQuote


Subject
Views
Written By
Posted
Re: Server Stopped Responding When Having Around 1000 Request
2495
November 22, 2012 06:49AM


Sorry, you can't reply to this topic. It has been closed.

Content reproduced on this site is the property of the respective copyright holders. It is not reviewed in advance by Oracle and does not necessarily represent the opinion of Oracle or any other party.