Откуда вдруг в insert куча log file sync?

expimp
Дата: 24.11.2009 14:38:17
Выполняю оператор
insert into coep_test
(select * from coep where rownum < 200000);

Операция выполняется 100 секунд (а на соседнем подобном сервере 5 секунд)

В statspack вижу


Top 5 Timed Events Avg %Total
~~~~~~~~~~~~~~~~~~ wait Call
Event Waits Time (s) (ms) Time
----------------------------------------- ------------ ----------- ------ ------
log file sync 346 223 645 47.8
log buffer space 127 111 877 23.9
log file parallel write 141 109 776 23.4
CPU time 13 2.9
control file parallel write 119 4 31 .8

То же самое и в трассировке после tkprof



call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 1 0.02 0.01 0 0 0 0
Execute 1 4.57 99.10 4936 27533 86574 199999
Fetch 0 0.00 0.00 0 0 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 4.59 99.12 4936 27533 86574 199999

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 43

Rows Row Source Operation
------- ---------------------------------------------------
199999 COUNT STOPKEY (cr=9553 pr=4934 pw=0 time=800237 us)
199999 TABLE ACCESS FULL COEP (cr=9553 pr=4934 pw=0 time=600884 us)


Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
db file sequential read 2 0.00 0.00
log buffer space 108 0.98 94.08
db file scattered read 40 0.03 0.66
SQL*Net message to client 1 0.00 0.00
SQL*Net message from client 1 0.00 0.00


Размер логов 400М
БД лежит на стойке (12 * 146Гб raid5)

Откуда берется ожидание log file sync да еще в таких количествах.
Как будто сервер сам коммитится после каждой строки.

В трассировке на соседнем сервере та же операция выглядит так

Top 5 Timed Events Avg %Total
~~~~~~~~~~~~~~~~~~ wait Call
Event Waits Time (s) (ms) Time
----------------------------------------- ------------ ----------- ------ ------
Log archive I/O 1,712 179 104 31.9
log file parallel write 702 94 134 16.8
db file scattered read 2,042 88 43 15.6
db file parallel write 216 62 289 11.1
CPU time 55 9.8

Приклеил весь statspack на всякий случай
Elic
Дата: 24.11.2009 14:48:20
expimp
Top 5 Timed Events                                                    Avg %Total
~~~~~~~~~~~~~~~~~~ wait Call
Event Waits Time (s) (ms) Time
----------------------------------------- ------------ ----------- ------ ------
log file sync 346 223 645 47.8
Средняя температура по больнице :)
expimp
То же самое и в трассировке после tkprof
А если протереть глазки?
expimp
Дата: 24.11.2009 14:56:31
Иронию понял. Написал криво - "То же самое и в трассировке после tkprof" означает, что есть еще tkprof!

И вот еще картинка из OEM
Elic
Дата: 24.11.2009 15:35:27
[quot expimp"То же самое и в трассировке после tkprof" означает, что есть еще tkprof![/quot]Ещё раз: там сабжа нет.
expimp
Дата: 24.11.2009 15:39:27
Так, что вижу в трассировке, то и выкладываю



********************************************************************************

insert into test.coep_test (select * from sapsr3.coep where rownum < 200000)



call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 1 0.02 0.01 0 0 0 0
Execute 1 4.57 99.10 4936 27533 86574 199999
Fetch 0 0.00 0.00 0 0 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 4.59 99.12 4936 27533 86574 199999

Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: 43

Rows Row Source Operation
------- ---------------------------------------------------
199999 COUNT STOPKEY (cr=9553 pr=4934 pw=0 time=800237 us)
199999 TABLE ACCESS FULL COEP (cr=9553 pr=4934 pw=0 time=600884 us)


Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
db file sequential read 2 0.00 0.00
log buffer space 108 0.98 94.08
db file scattered read 40 0.03 0.66
SQL*Net message to client 1 0.00 0.00
SQL*Net message from client 1 0.00 0.00
pravednik
Дата: 24.11.2009 15:40:52
expimp,

Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
db file sequential read 2 0.00 0.00
log buffer space 108 0.98 94.08
db file scattered read 40 0.03 0.66
SQL*Net message to client 1 0.00 0.00
SQL*Net message from client 1 0.00 0.00

где тут " куча log file sync? "
expimp
Дата: 24.11.2009 15:46:12
Вот здесь


Top 5 Timed Events Avg %Total~~~~~~~~~~~~~~~~~~ wait CallEvent Waits Time (s) (ms) Time----------------------------------------- ------------ ----------- ------ ------log file sync 346 223 645 47.8log buffer space 127 111 877 23.9log file parallel write 141 109 776 23.4CPU time 13 2.9control file parallel write 119 4 31 .8

И на картинке EM.

statspack запускал только в обрез с запуском insert и тут же выключил.
В БД никто не работает. Хочу сообразить, откуда лезут log file sync.
expimp
Дата: 24.11.2009 15:47:26
FIX не сработал в предыдущем сообщении. Опять повторю.


Top 5 Timed Events Avg %Total
~~~~~~~~~~~~~~~~~~ wait Call
Event Waits Time (s) (ms) Time
----------------------------------------- ------------ ----------- ------ ------
log file sync 346 223 645 47.8
log buffer space 127 111 877 23.9
log file parallel write 141 109 776 23.4
CPU time 13 2.9
control file parallel write 119 4 31 .8
pravednik
Дата: 24.11.2009 15:50:15
expimp
FIX не сработал в предыдущем сообщении. Опять повторю.

вы различаете вывод статспак/EM/AWR("Средняя температура по больнице :)") и "То же самое и в трассировке после tkprof" ??
suPPLer
Дата: 24.11.2009 15:51:39
expimp
Вот здесь


Это отчёт STATSPACK. Где log file sync в файле от tkprof?