hi all
В одну базейку пишет ОДИН коннект от ISQL. И иногда (после каждой 50-й транзакции) запрашивает gen_id() и mon$database, дабы выдать на гор а текущую инфу.
В базе установлен sweep_int = 20000.
Из-за того, что я не закоммитил в другом ISQL какой-то селект, в базе стал накапливаться зазор для свипа. Дальше я прочухался и сделал commit+quit, в результате чего в базе скоро запустился sweep.
Я могу понять, что свип чего-то там сильно грузит, и поэтому *между* сообщениями в трейсе о его прогрессе возможно появления следующего перла: 1.
2.
3.
4.
5.
6.
7.
8.
9.
10.
11.
12.
13.
14.
15.
16.
17.
18.
19.
20.
21.
22.
23.
24.
25.
26.
27.
28.
29.
30.
2015-11-12T16:18:06.3800 (2704:000000002257E8C0) SWEEP_PROGRESS
E:\OLTP-EMUL\OLTP30.FDB (ATT_21614, SWEEPER:NONE, NONE, <internal>)
0 ms, 251 fetch(es)
Table Natural Index Update Insert
***************************************************************************
INVNT_TURNOVER_LOG 121
2015-11-12T16:18:10.8940 (2704:000000002257F3C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21617, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6720
(TRA_333996, CONCURRENCY | WAIT | READ_WRITE)
Statement 1155487:
---------------------------------------------------------------------------
select * from mon$database
1 records fetched
4276 ms, 5 read(s), 2 write(s), 69 fetch(es)
Table Natural Index Update Insert
***************************************************************************
RDB$RELATIONS 16
2015-11-12T16:18:11.6900 (2704:000000002257E8C0) SWEEP_PROGRESS
E:\OLTP-EMUL\OLTP30.FDB (ATT_21614, SWEEPER:NONE, NONE, <internal>)
5309 ms, 90346 read(s), 1125 write(s), 976290 fetch(es), 170674 mark(s)
Table Natural Index Update Insert
***************************************************************************
PERF_LOG 222583
Но кто-нить может объяснить, почему ПОСЛЕ завершения свипа такой же запрос к mon$database думкал свыше 1 секунды: 1.
2.
3.
4.
5.
6.
7.
8.
9.
10.
11.
12.
13.
14.
15.
16.
17.
18.
19.
20.
21.
22.
23.
24.
25.
26.
27.
28.
29.
30.
31.
32.
33.
34.
35.
36.
37.
38.
39.
40.
41.
42.
43.
44.
45.
46.
47.
48.
49.
50.
51.
52.
53.
54.
55.
56.
57.
58.
59.
60.
61.
2015-11-12T16:18:22.8480 (2704:000000002257E8C0) SWEEP_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21614, SWEEPER:NONE, NONE, <internal>)
Transaction counters:
Oldest interesting 333988
Oldest active 334031
Oldest snapshot 334031
Next transaction 334032
17484 ms, 342488 read(s), 15206 write(s), 5749217 fetch(es), 642865 mark(s)
Table Natural Index Update Insert Delete Backout Purge Expunge
***************************************************************************************************************
RDB$PAGES 836
RDB$DATABASE 2
RDB$INDEX_SEGMENTS 61
RDB$INDICES 38
RDB$RELATION_FIELDS 1
RDB$RELATIONS 1 57
RDB$VIEW_RELATIONS 1
RDB$FORMATS 9
RDB$SECURITY_CLASSES 4 1
RDB$DEPENDENCIES 2
RDB$USER_PRIVILEGES 2
SETTINGS 2
DOC_LIST 18191
DOC_DATA 104178
MONEY_TURNOVER_LOG 13
MONEY_SALDO 49
INVNT_SALDO 400
PDISTR 37565
PSTORNED 28261 171
SEMAPHORES 3
INVNT_TURNOVER_LOG 121
PERF_LOG 222583
XQD_1000_1200 923
XQD_1200_2000 84
XQD_1000_3300 290
XQD_2000_3300 181611 1961
XQD_2100_3300 220320 26
XQD_3300_3400 163902 118
XQS_1000_1200 221643 227
XQS_1200_2000 222028 134
XQS_1000_3300 166418 1689
XQS_2100_3300 166627 1392
XQS_3300_3400 71947 452
2015-11-12T16:18:22.8790 (2704:000000002257F3C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21617, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6720
(TRA_334032, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1156077:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1111 ms, 6 fetch(es)
К базе, повторюсь, других коннектов нету. Что там он мог так долго собирать в мониторинге ?
ЗЫ. Обычное время выполнения запроса к mon$database - 1-2 мс & 6 fetches. 1.
2.
3.
4.
5.
6.
7.
8.
9.
10.
11.
12.
13.
14.
15.
16.
17.
18.
19.
20.
21.
22.
23.
24.
25.
26.
27.
28.
29.
30.
31.
32.
33.
34.
35.
36.
37.
38.
39.
40.
41.
42.
43.
44.
45.
46.
47.
48.
49.
50.
51.
52.
53.
54.
55.
56.
57.
58.
59.
60.
61.
62.
63.
64.
65.
66.
67.
68.
69.
70.
71.
72.
73.
74.
75.
76.
77.
78.
79.
80.
81.
82.
83.
84.
85.
86.
87.
88.
89.
90.
91.
92.
93.
94.
95.
96.
97.
98.
99.
100.
101.
102.
103.
104.
105.
106.
107.
108.
109.
110.
111.
112.
113.
114.
115.
116.
117.
118.
119.
120.
121.
122.
123.
124.
125.
126.
127.
128.
129.
130.
131.
132.
133.
134.
135.
136.
137.
138.
139.
140.
141.
142.
143.
144.
145.
146.
147.
148.
149.
150.
151.
152.
153.
154.
155.
156.
157.
158.
159.
160.
161.
162.
163.
164.
165.
166.
167.
168.
169.
170.
171.
172.
173.
174.
175.
176.
177.
178.
179.
180.
181.
182.
183.
184.
185.
186.
187.
188.
189.
190.
191.
192.
193.
194.
195.
196.
197.
198.
199.
200.
201.
202.
203.
204.
205.
206.
207.
208.
209.
210.
211.
212.
213.
214.
215.
216.
217.
218.
219.
220.
221.
222.
223.
224.
225.
226.
227.
228.
229.
230.
231.
232.
233.
234.
235.
236.
237.
238.
239.
240.
241.
242.
243.
244.
245.
246.
247.
248.
249.
250.
251.
252.
253.
254.
255.
256.
257.
258.
259.
260.
261.
262.
263.
264.
265.
266.
267.
268.
269.
270.
271.
272.
273.
274.
275.
276.
277.
278.
279.
280.
281.
282.
283.
284.
285.
286.
287.
288.
289.
290.
291.
292.
293.
294.
295.
296.
297.
298.
299.
300.
301.
302.
303.
304.
305.
306.
307.
308.
309.
310.
311.
312.
313.
314.
315.
316.
317.
318.
319.
320.
321.
322.
323.
324.
325.
326.
327.
328.
329.
330.
331.
332.
333.
334.
335.
336.
337.
338.
339.
340.
341.
342.
343.
344.
345.
346.
347.
348.
349.
350.
351.
352.
353.
354.
355.
356.
357.
358.
359.
360.
361.
362.
363.
364.
365.
366.
367.
368.
369.
370.
371.
372.
373.
374.
375.
376.
377.
378.
379.
380.
381.
382.
383.
384.
385.
386.
387.
388.
389.
390.
391.
392.
393.
394.
395.
396.
397.
398.
399.
400.
401.
402.
403.
2015-11-12T16:40:12.6640 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339424, CONCURRENCY | WAIT | READ_WRITE)
Statement 1257957:
-------------------------------------------------------------------------------
select * from mon$database
1 records fetched
1 ms, 69 fetch(es)
Table Natural Index Update Insert Delete Backout Purge Expunge
***************************************************************************************************************
RDB$RELATIONS 16
2015-11-12T16:40:13.7560 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339425, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258046:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:14.7080 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339426, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258061:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:14.7550 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339427, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258084:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
2 ms, 6 fetch(es)
2015-11-12T16:40:14.9730 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339428, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258101:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
3 ms, 6 fetch(es)
2015-11-12T16:40:15.0980 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339429, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258117:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:15.1290 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339430, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258136:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:15.1600 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339431, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258152:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
3 ms, 6 fetch(es)
2015-11-12T16:40:15.2540 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339432, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258166:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
3 ms, 6 fetch(es)
2015-11-12T16:40:15.4100 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339433, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258178:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
3 ms, 6 fetch(es)
2015-11-12T16:40:15.7220 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339434, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258193:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:15.7530 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339435, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258209:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:15.7690 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339436, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258225:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
2015-11-12T16:40:15.8000 (2704:000000002257E8C0) EXECUTE_STATEMENT_FINISH
E:\OLTP-EMUL\OLTP30.FDB (ATT_21977, SYSDBA:NONE, NONE, TCPv6:::1)
E:\FB30.TMPINSTANCE\isql.exe:6488
(TRA_339437, CONCURRENCY | NOWAIT | READ_WRITE)
Statement 1258241:
-------------------------------------------------------------------------------
select
m.mon$database_name db_name,
rdb$get_context('SYSTEM','ENGINE_VERSION') engine,
MON$FORCED_WRITES db_forced_writes,
MON$PAGE_BUFFERS page_buffers,
m.mon$page_size * m.mon$pages as db_current_size,
gen_id(g_init_pop,0) as new_docs_created
from mon$database m
1 records fetched
1 ms, 6 fetch(es)
|
