ReSQL.ru
     
powered by simpleCommunicator - 2.0.61     © 2026 Programmizd 02
Мобильная версия    Контакт    Правила    FAQ    Помощь
Целевая тема:
Создать новую тему:
Автор:
Закрыть
Цитировать
Доб. в избранное | Игнор. тему | Прикреп. тему | Пометить прочит. / непрочит. | Фильтр
Форумы / Firebird, InterBase [игнор отключен] [закрыт для гостей] / trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
11 сообщений из 11, страница 1 из 1
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565090
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
hi all

LI-T3.0.0.30889

Запустил тугой запрос на копии продакшена, в другом окне - трейс с конфигом:
Код: plaintext
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.
database = # %[\\/]tmp20140106.fdb
{
  enabled = true
  log_sweep = true
  log_errors = true

  ####################   A C H T U N G  ##############
   time_threshold = 0 
  ####################################################

  log_connections = true
  log_transactions = true

#  log_statement_prepare = true
#  log_statement_start = true
#  log_statement_free = true
#  log_trigger_start = true
#  log_procedure_start = true

  log_statement_finish = true
  log_procedure_finish = true
  log_trigger_finish = true

   print_plan = true
  print_perf = true 
  max_sql_length = 16384
  max_log_size = 9999999999
  #log_filename = /opt/fb30cs/ztrace.log
}

В третьем окне ввожу:
Код: plaintext
SQL> select current_timestamp from rdb$database; commit; delete from mon$statements where mon$attachment_id<>current_connection; select current_timestamp from rdb$database;

Он мне тут же выдаёт:
Код: plaintext
1.
2.
3.
CURRENT_TIMESTAMP               2014-02-18 19:13:16.8460

CURRENT_TIMESTAMP               2014-02-18  19:13:16 .8870
- но трейс еще несколько секунд продолжает показывать активность работающего запроса-тугодума.
В итоге, в окне трейса вылазит:
Код: plaintext
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.
. . .

2014-02-18T 19:13:16 .4920 (23045:0x7f76110dcfe0) EXECUTE_TRIGGER_FINISH
        /var/db/fb30/production30.fdb (ATT_14, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:1356
                (TRA_1263, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)
        TMP$FIN_CACHE_BI0 FOR TMP$FIN_CACHE (BEFORE INSERT)
      0 ms, 1 fetch(es), 1 mark(s)


 --- Session 12 is suspended as its log is full --- 
2014-02-18T 19:13:19 .2220 (23045:0x7f76110de480) TRACE_INIT
        SESSION_12


2014-02-18T19:13:19.2220 (23045:0x7f76110de480) ERROR AT JStatement::fetch
        /var/db/fb30/production30.fdb (ATT_14, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:1356
 335544794 : operation was cancelled 

2014-02-18T19:13:19.2230 (23045:0x7f76110de480) EXECUTE_TRIGGER_FINISH
        /var/db/fb30/production30.fdb (ATT_14, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:1356
                (TRA_1263, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)
        TRG_COMMIT (ON TRANSACTION_COMMIT)
      0 ms

2014-02-18T19:13:19.2320 (23045:0x7f76110de480) COMMIT_TRANSACTION
        /var/db/fb30/production30.fdb (ATT_14, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:1356
                (TRA_1263, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)
      9 ms, 1269 write(s), 7 fetch(es), 4 mark(s)

ВОПРОС-1.
С какого перепугу вылезло сообщение сабжа, когда лимит лога задан "мама не горюй", а размер созданного лога трейса равен в этот момент всего 21.5 Мб ?

ВОПРОС-2.
В где статистика обломанного (срубленного) запроса ? Разве её не должно быть при срубании через delete from mon$statements ?
...
Рейтинг: 0 / 0
18.02.2014, 19:23:37
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565138
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
а еще я вижу вот что: если в конфиге трейса поменять time_threshold на 1000 и снова запустить тугой запрос, подождать полчасика и затем снова срубить его, то в трейсе *не* будет показано ничего на тему "operation was cancelled".
Я срубил запрос в 2014-02-18 19:59:33.6920, в трейсе же вижу только вот это:
 
Код: plaintext
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.
2014-02-18T19:59:33.5810 (23045:0x7f76110de480) TRACE_INIT
        SESSION_13


2014-02-18T19:59:33.5820 (23045:0x7f76110de480) COMMIT_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1265, CONCURRENCY | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T19:59:33.5820 (23045:0x7f76110de480) COMMIT_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1266, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T19:59:33.5820 (23045:0x7f76110de480) START_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1268, CONCURRENCY | WAIT | READ_WRITE)

2014-02-18T19:59:33.5820 (23045:0x7f76110de480) START_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1269, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)

-- это я уже закрыл само приложение (думал, может тогда появится мессага о
-- срубленном стейтменте):
2014-02-18T20:00:46.9380 (23045:0x7f76110d7390) TRACE_INIT
        SESSION_13


2014-02-18T20:00:46.9380 (23045:0x7f76110d7390) DETACH_DATABASE
        /var/db/fb30/production30.fdb (ATT_15, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:1356

2014-02-18T20:00:46.9390 (23045:0x7f76110d7390) TRACE_FINI
        SESSION_13
...
Рейтинг: 0 / 0
18.02.2014, 20:07:16
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565142
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
Таблоида еще я вижу вот что: если в конфиге трейса поменять time_threshold на 1000 и снова запустить тугой запрос, подождать полчасика и затем снова срубить его, то в трейсе *не* будет показано ничего на тему "operation was cancelled".... а при time_threshold 100 - всё пучком:
 
Код: plaintext
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.
supertee -n logs/trc3333_20140218_200811.log /opt/fb30trnk/bin/fbtracemgr -sta -c ztrace30.conf -se localhost/3333:service_mgr
Trace session ID 14 started
2014-02-18T20:08:21.2720 (23045:0x7f76110d7390) TRACE_INIT
        SESSION_14


2014-02-18T20:08:21.2720 (23045:0x7f76110d7390) DETACH_DATABASE
        /opt/fb30trnk/security3.fdb (ATT_7692, SYSDBA:NONE, NONE, <internal>)

2014-02-18T20:08:21.2720 (23045:0x7f76110d7390) TRACE_FINI
        SESSION_14


2014-02-18T20:08:40.3150 (23045:0x7f76110d7390) TRACE_INIT
        SESSION_14


2014-02-18T20:08:40.3160 (23045:0x7f76110d7390) START_TRANSACTION
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
                (TRA_1435, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)

2014-02-18T20:09:04.3980 (23045:0x7f76110dc520) TRACE_INIT
        SESSION_14


2014-02-18T20:09:04.4000 (23045:0x7f76110dc520) COMMIT_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1268, CONCURRENCY | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T20:09:04.4000 (23045:0x7f76110dc520) COMMIT_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1269, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T20:09:04.4000 (23045:0x7f76110dc520) START_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1436, CONCURRENCY | WAIT | READ_WRITE)

2014-02-18T20:09:04.4000 (23045:0x7f76110dc520) START_TRANSACTION
        prod30 (ATT_16, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb30trnk/bin/isql:29316
                (TRA_1437, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)

2014-02-18T20:09:09.4330 (23045:0x7f76110d7390) FAILED EXECUTE_PROCEDURE_FINISH
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
                (TRA_1435, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)

Procedure FIN_SET_ANALYTICS_CAL▒▒▒:
param0 = integer, "2004400"
param1 = integer, "0"
param2 = integer, "15405"
param3 = integer, "1008452"
param4 = integer, "0"
param5 = integer, "0"
param6 = integer, "0"
param7 = integer, "0"
param8 = integer, "0"
param9 = integer, "0"

  29022 ms, 577 write(s), 10147449 fetch(es), 1747665 mark(s)

Table                             Natural     Index    Update    Insert    Delete   Backout     Purge   Expunge
***************************************************************************************************************
CFG                                             104
XREF                                         403939
FIN_ANALYTICS                                628119
TMP$FIN_CACHE                                                    216461
TMP$REF_CACHE                                432818                 104

2014-02-18T20:09:09.4340 (23045:0x7f76110d7390) FAILED EXECUTE_PROCEDURE_FINISH
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
                (TRA_1435, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)

Procedure FIN_TURNOVER_BALANCE▒▒▒▒:
param0 = timestamp, "2014-02-17T00:00:00.0000"
param1 = timestamp, "2014-02-17T00:00:00.0000"
param2 = integer, "301"
param3 = integer, "0"
param4 = integer, "0"
param5 = integer, "<NULL>"
param6 = integer, "2004400"
param7 = integer, "1008452"
param8 = integer, "0"
param9 = integer, "0"
param10 = integer, "0"
param11 = integer, "0"
param12 = integer, "15405"
param13 = integer, "0"
param14 = integer, "0"
param15 = integer, "0"
param16 = integer, "2000194"

  29022 ms, 577 write(s), 16023679 fetch(es), 3489452 mark(s)

Table                             Natural     Index    Update    Insert    Delete   Backout     Purge   Expunge
***************************************************************************************************************
REF                                               6
CFG                                             104
AUXVAL                                            4
XREF                                         403939
FIN_ANALYTICS                                628119
TMP$FIN_CACHE                                                    216461              216461
TMP$REF_CACHE                                432818                 104                 104

2014-02-18T20:09:09.4340 (23045:0x7f76110d7390) FAILED EXECUTE_STATEMENT_FINISH
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
                (TRA_1435, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)

Statement 1409:
-------------------------------------------------------------------------------
select
f.*,
f.amount_start_saldo_debet as a_start_saldo_debet,
f.amount_start_saldo_credit as a_start_saldo_credit,
f.amount_transaction_debet as a_transaction_debet,
f.amount_transaction_credit as a_transaction_credit,
f.amount_end_saldo_debet as a_end_saldo_debet,
f.amount_end_saldo_credit as a_end_saldo_credit,
cast(?               as integer) as a_start_saldo_debet_visible,
cast(?               as integer) as a_start_saldo_credit_visible,
cast(?               as integer) as a_transaction_debet_visible,
cast(?               as integer) as a_transaction_credit_visible,
cast(?               as integer) as a_end_saldo_debet_visible,
cast(?               as integer) as a_end_saldo_credit_visible
from
fin_turnover_balance_roll(?     , ?     , ?           , ?         , ?              ,
null, ?        , ?        , ?        , ?            , ?            , ?            ,
        ?            , ?            , ?            , 0, ?                ) f

^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
PLAN (F NATURAL)

param0 = integer, "0"
param1 = integer, "0"
param2 = integer, "0"
param3 = integer, "0"
param4 = integer, "0"
param5 = integer, "0"
param6 = timestamp, "2014-02-17T00:00:00.0000"
param7 = timestamp, "2014-02-17T00:00:00.0000"
param8 = integer, "301"
param9 = integer, "0"
param10 = integer, "0"
param11 = integer, "2004400"
param12 = integer, "1008452"
param13 = integer, "0"
param14 = integer, "0"
param15 = integer, "0"
param16 = integer, "0"
param17 = integer, "15405"
param18 = integer, "0"
param19 = integer, "0"
param20 = integer, "2000194"

0 records fetched
  29023 ms, 577 write(s), 16023679 fetch(es), 3489452 mark(s)

Table                             Natural     Index    Update    Insert    Delete   Backout     Purge   Expunge
***************************************************************************************************************
REF                                               6
CFG                                             104
AUXVAL                                            4
XREF                                         403939
FIN_ANALYTICS                                628119
TMP$FIN_CACHE                                                    216461              216461
TMP$REF_CACHE                                432818                 104                 104

2014-02-18T20:09:09.4340 (23045:0x7f76110d7390) ERROR AT JStatement::fetch
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
335544794 : operation was cancelled

2014-02-18T20:09:09.4550 (23045:0x7f76110d7390) COMMIT_TRANSACTION
        /var/db/fb30/production30.fdb (ATT_17, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2532
                (TRA_1435, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)
     20 ms, 2896 write(s), 7 fetch(es), 4 mark(s)

PS. Не понятно, впрочем, что повлияло: то ли уменьшение time_threshold, то ли нетерпёж и срубание уже через 30 сек, а не через полчаса.
...
Рейтинг: 0 / 0
18.02.2014, 20:13:13
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565164
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
ТаблоидНе понятно, впрочем, что повлияло: то ли уменьшение time_threshold, то ли нетерпёж и срубание уже через 30 сек, а не через полчаса.Теперь понятно. Влияет время работы запроса, а не time_threshold. Хрен его поймёт, что он там делает внутри (читает из "много чего" и пишет в две GTTшки), но если прождать 20 минут с тем же time_threshold = 100 и затем срубить - онять в трейсе не показывается ничего на тему отмены операции :(
...
Рейтинг: 0 / 0
18.02.2014, 20:38:35
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565198
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
"Продолжаем разговор" (С) :-)

В 2.5 запрос молотил около получаса, затем также был срублен через delete from mon$statements.
В трейсе операция облома отразилась , в отличие от 3.0:
 
Код: plaintext
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.
$ ./fb25_trace.sh

trace start, see logs/trc25_20140218_204258.log

Trace session ID 15 started
2014-02-18T20:43:09.7630 (23439:0x7f1330f06340) TRACE_INIT
        SESSION_15


2014-02-18T 20:43:09 .7630 (23439:0x7f1330f06340) START_TRANSACTION
        /var/db/fb25/production25.fdb (ATT_12, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2828
                (TRA_2034, READ_COMMITTED | REC_VERSION | NOWAIT | READ_ONLY)

-- через полчаса в isql-окне было введено delete from mon$statements:
2014-02-18T 21:15:29 .5150 (23439:0x7f1330f047a8) TRACE_INIT
        SESSION_15


2014-02-18T21:15:29.5250 (23439:0x7f1330f047a8) COMMIT_TRANSACTION
        prod25 (ATT_14, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb25/bin/isql:31465
                (TRA_2032, CONCURRENCY | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T21:15:29.5250 (23439:0x7f1330f047a8) COMMIT_TRANSACTION
        prod25 (ATT_14, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb25/bin/isql:31465
                (TRA_2033, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)
      0 ms, 1 write(s), 1 fetch(es), 1 mark(s)

2014-02-18T21:15:29.5290 (23439:0x7f1330f047a8) START_TRANSACTION
        prod25 (ATT_14, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb25/bin/isql:31465
                (TRA_2035, CONCURRENCY | WAIT | READ_WRITE)

2014-02-18T21:15:29.5290 (23439:0x7f1330f047a8) START_TRANSACTION
        prod25 (ATT_14, SYSDBA:NONE, NONE, TCPv4:127.0.0.1)
        /opt/fb25/bin/isql:31465
                (TRA_2036, READ_COMMITTED | NO_REC_VERSION | WAIT | READ_WRITE)

-- и через 15 секунд в isql был получен "отклик" (выведено второе значение таймштампа),
-- что соотв-вует моменту времени появления этого в трейсе:
2014-02-18T21:15:54.4330 (23439:0x7f1330f05bc0) TRACE_INIT
        SESSION_15


2014-02-18T21:15:54.4330 (23439:0x7f1330f05bc0) ERROR AT jrd8_database_info
        /var/db/fb25/production25.fdb (ATT_13, PRODUSER:NONE, WIN1251, TCPv4:192.168.43.96)
        c:\MIX\prodcopy\prodcopy.exe:2828
 335544794 : operation was cancelled
...
Рейтинг: 0 / 0
18.02.2014, 21:23:33
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565206
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
2 dimitr / hvlad: поскольку команда delete from mon$statements выполнялась 15 сек:
Код: plaintext
1.
2.
3.
CURRENT_TIMESTAMP               2014-02-18 21:15:29.5160

CURRENT_TIMESTAMP               2014-02-18 21:15:54.2760
- мне удалось собрать бактрассы процесса fbserver за этот промежуток времени. Они в аттаче.
В именах файлов присутствует время их создания с точносью до секунд, т.е. бактрассы
Код: plaintext
1.
2.
3.
gdb.140218_211531.fbserver.23439.txt
gdb.140218_211536.fbserver.23439.txt
gdb.140218_211541.fbserver.23439.txt
gdb.140218_211546.fbserver.23439.txt
- собирались именно в период, когда delete from mon$statements был уже введён, но еще не завершен.

Я их собрал потому, что не могу понять "систему": иногда эта команда отрабатывает мгновенно, а иногда - тупит по 10-20 сек.
...
Рейтинг: 0 / 0
18.02.2014, 21:27:52
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565295
dimitr
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
dimitr
Участник
Таблоид,

в твоих дампах сервер выполняет откат изменений
...
Рейтинг: 0 / 0
18.02.2014, 23:23:14
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565334
hvlad
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
hvlad
Участник
Таблоид
Код: plaintext
max_log_size = 9999999999
...
Код: plaintext
--- Session 12 is suspended as its log is full ---
...
ВОПРОС-1.
С какого перепугу вылезло сообщение сабжа, когда лимит лога задан "мама не горюй", а размер созданного лога трейса равен в этот момент всего 21.5 Мб ?а) размер файла аудита не имеет никакого отношения к пользовательскому трейсу
б) твой клиент читает лог трейса медленнее, чем сервер его пишет, в результате на сервере накопилось более 10МБ непрочитанных логов твоей сессии
в) ты не читаешь документацию (для кого мы её пишем ?)
...
Рейтинг: 0 / 0
19.02.2014, 00:37:35
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565345
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
dimitrв твоих дампах сервер выполняет откат изменений2 dimitr / hvlad: есть ли возможность добавить в лог трейса такое событие, как start / finish этих самых откатов ? Еще лучше - чтобы статистику было видно (время вып-я, и какие таблицы оказались задействованы)
А то они, откаты эти, как суслики известного фильма: никто их не видит, но все знают, что они есть.
hvladб) твой клиент читает лог трейса медленнее, чем сервер его пишет, в результате на сервере накопилось более 10МБ непрочитанных логов твоей сессии
в) ты не читаешь документацию (для кого мы её пишем ?)
Каюсь, забыл я про этот параметр. :-[
firebird.conf
Код: plaintext
1.
2.
3.
4.
5.
# Maximum summary size of each user trace session's log files in MB.
# When log files size reach this limit, trace session automatically 
# suspends until interactive user service read and delete some log files.
...
#MaxUserTraceLogSize =  10

Только всё равно неясности есть.

1) Перезапуск запроса показал, что трейс создаёт что-то типа "очереди отложенной записи" в виде файлов по 1 Мб. Они создаются в каталоге /tmp/firebird (хотя у мну env-переменная tmp указана на другой каталог, а tempDirs = /dev/shm; tmp - именно в такой последовательности). Эта папка - она жестко прошита ?
2) Очередь из этих файлов в /dev/shm - не была бы более эффективной (если этот /dev/shm указан в TempDirs) ?
3) Почему нельзя было ввести вместо параметра MaxUserTraceLogSize что-то типа MinDiskFreeWhenTrace, mb, дабы гарантировать остановку трейса при реальной угрозе исчерпания дискового пространства, а не из-за увеличения очереди файлов до 11 штук ?
4) при срубании трейса (по Ctrl-C), да и при остановке ФБ, в каталоге /tmp/firebird остаются неудалёнными какие-то "резидентные" файлы вида:
Код: plaintext
1.
2.
3.
$ ls -l fb_trace*
  4160 Feb 19 00:43 fb_trace
 14611 Feb 19 00:43 fb_trace_p3yPWh
   868 Feb 19 01:12 fb_trace_qqcNkv
Каталог этот в итоге становится форменной помойкой.
BTW: после остановки ФБ в каталоге /tmp/firebird остались также:
Код: plaintext
1.
2.
1048576 Feb 19 00:54 fb12_monitor_0408000000000000f603640000000000
      0 Feb 19 00:55 fb_init
1048576 Feb 19 00:57 fb_lock_0408000000000000f603640000000000

5) кто именно управляет записью "очереди файлов" fb_trace_*.0000nnn: движок или fbtracemgr ?
...
Рейтинг: 0 / 0
19.02.2014, 01:22:38
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565353
hvlad
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
hvlad
Участник
Таблоид,

1. логи трейса живут в каталоге лок-таблицы
2. с чего бы это ?
3. сам подумай
4. при срубании процесса он не может убрать за собой, это новость ?
5. коннекты\сервисы пишут (используя движок), клиент трейс-сервиса читает и удаляет прочитанное (используя опять же движок)
...
Рейтинг: 0 / 0
19.02.2014, 01:53:24
| Ответить | Цитировать | Написать  
trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
    #38565358
Таблоид
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Таблоид
Участник
hvlad1. логи трейса живут в каталоге лок-таблицыони должны обязательно там быть вместе с ней, в одном каталоге?
hvlad2. с чего бы это ?ну, как... ram-диск типа... быстрее должен быть как бэ... :-)
hvlad3. сам подумайЧтобы не думкал, всё равно будет не то. Дай сразу ответ, пож-ста.
Вот трейс пишет-пишет и периодически (например, 1 раз на каждый в мегабайт) проверяет диск: "ну чё, много там еще ?" - и стопарит, когда уже маловато.
Что не так ?
hvlad4. при срубании процесса он не может убрать за собой, это новость ?Вообще-то, срубание по Ctrl-C трейса прошло с УДАЛЕНИЕМ файлов очереди. А ФБ я не срубал, а попросил остановться :
Код: plaintext
/etc/init.d/fb30trnk stop
Именно "попросил": потому что иногда эта команда выдаёт кроваво-красным цветом [ FAILED ].
...
Рейтинг: 0 / 0
19.02.2014, 02:04:24
| Ответить | Цитировать | Написать  
11 сообщений из 11, страница 1 из 1
Форумы / Firebird, InterBase [игнор отключен] [закрыт для гостей] / trace: выдает "Session NN is suspended as its log is full" при обрубе стейтмента. Why ?
Найденые пользователи ...
Разблокировать пользователей ...
Читали тему (0):
Читали форум (0):
Пользователи онлайн (0):
Польз. соглашение   Полит. конфиден.  
NoSQL.ru       Кролег: Проекты, Новости, Чат       РЕД ФОРУМ       Фотоальбом: Участники, Встречи
Закрыть
start [/forum/search_topic.php?author=natalian&author_mode=last_posts&do_search=1]:
 0ms
get settings:
 8ms
get forum list:
 18ms
get settings:
 10ms
get forum list:
 13ms
check forum access:
 3ms
check topic access:
 3ms
track hit:
 64ms
get topic data:
 10ms
get forum data:
 2ms
get page messages:
 46ms
get tp. blocked users:
 1ms
others: 434ms
total:  612ms
созд. / загр.: 612ms
x
x
Закрыть


Просмотр
0 / 0
Close
Debug Console [Select Text]