Гость
Войти | Профиль | Очистить
Действия ...
Форумы / Oracle [игнор отключен] [закрыт для гостей] / Анализ трассировочных файлов. Источник возникновения ожидания / 6 сообщений из 6, страница 1 из 1
06.02.2020, 10:26
    #39923224
MadBox
MadBox
Гость
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
Добрый день!
Нужна помощь в анализе трассировочных файлов. Есть 2 БД Oracle 11g прод и предпрод, идентичны по железу и данным. Один и тот же пакет, для расчета витрин данных на прод базе среде выполняется за 1 час, на предпрод за 32 минуты. DBA утверждают, что разбежка возникает из-за логирования и репликации на проде (на предпроде отключены). Снял трассу по самой "тяжёлой" процедуре, прогнал через TKPROF:

Прод:
Код: plsql
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.
call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      1    336.45    2036.89    1394030   14622771       2375     2255256
Fetch        0      0.00       0.00          0          0          0           0
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total        2    336.45    2036.89    1394030   14622771       2375     2255256

Elapsed times include waiting on following events:
  Event waited on                             Times   Max. Wait  Total Waited
  ----------------------------------------   Waited  ----------  ------------
  Disk file operations I/O                       34        0.00          0.00
  db file sequential read                    127693        0.39        746.45
  latch: row cache objects                        1        0.00          0.00
  PX Deq: Join ACK                                4        0.00          0.00
  PX Deq Credit: send blkd                        1        0.00          0.00
  PX qref latch                                   1        0.00          0.00
  PX Deq Credit: free buffer                      3        0.00          0.00
  PX Deq: Parse Reply                             4        2.16          2.16
  PX Deq: Execute Reply                          16        0.01          0.01
  db file scattered read                       7202        0.15        101.93
  direct path read temp                       76238        0.16        852.81
  direct path write temp                       5045        0.05          1.59
  db file parallel read                           1        0.03          0.03
  PX Deq Credit: need buffer                      7        0.00          0.00
  reliable message                                1        0.00          0.00
  enq: CR - block range reuse ckpt                1        0.01          0.01
  PX Deq: Signal ACK RSG                          4        0.00          0.00
********************************************************************************


Предпрод:

Код: plsql
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.
call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.00       0.00          0          0          0           0
Execute      1    318.83    1363.52    1965651   14911127       6754     2255256
Fetch        0      0.00       0.00          0          0          0           0
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total        2    318.84    1363.53    1965651   14911127       6754     2255256

Elapsed times include waiting on following events:
  Event waited on                             Times   Max. Wait  Total Waited
  ----------------------------------------   Waited  ----------  ------------
  db file sequential read                    478133        0.29        991.26
  Disk file operations I/O                       16        0.00          0.00
  PX Deq: Join ACK                                4        0.00          0.00
  PX Deq Credit: send blkd                        2        0.00          0.00
  PX Deq Credit: need buffer                      4        0.02          0.02
  PX Deq: Parse Reply                             4        1.81          1.82
  PX Deq: Execute Reply                          16        0.00          0.01
  db file scattered read                       6864        0.10         22.23
  direct path write temp                      71579        0.05         45.86
  local write wait                             5856        0.00          2.77
  direct path read temp                       96801        0.00          2.57
  log file switch (private strand flush incomplete)
                                                  1        0.00          0.00
  db file parallel read                           1        0.00          0.00
  log file switch completion                      3        0.00          0.01
  reliable message                                4        0.00          0.00
  enq: CR - block range reuse ckpt                1        0.00          0.00
  PX Deq: Signal ACK RSG                          4        0.00          0.00
********************************************************************************


Процессорное время выполнения +/- совпадает, а вот фактическое довольно существенно разнится, на проде + 11 минут. Вижу что проблема в ожиданиях в частности "direct path read temp ". Подскажите в какую сторону копать, чтобы понять источник и причину возникновения этих ожиданий.
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
06.02.2020, 10:36
    #39923231
-2-
-2-
Участник
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
MadBox
Вижу что проблема в ожиданиях в частности "direct path read temp ".
Как "DBA утверждают" комментируют значительно большее время ожидания при незначительно меньших количествах?
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
06.02.2020, 11:27
    #39923276
landy
landy
Участник
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
MadBox,
инфраструктура какая?
Дисковые массивы?
Судя по всему на предпроде диски работают быстрее
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
06.02.2020, 11:45
    #39923292
Sayan Malakshinov
Sayan Malakshinov
Модератор форума
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
MadBox,

Почти 6мс на одноблочное чтение это, конечно, хреново, но вообще не в ту сторону вы смотрите - оставьте кесарево кесарю и начните с оптимизации своего кода. Очевидно, что на препроде нагрузка меньше, чем на проде, а железо по вашим же словам идентичное.
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
06.02.2020, 12:03
    #39923298
проходил мимо...
проходил мимо...
Участник
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
MadBox
DBA утверждают, что разбежка возникает из-за логирования и репликации на проде (на предпроде отключены)

дык, включите на предпроде! может быть "логирование" и "репликация" действительно сжирают весь потенциал дискового ввода-вывода. но я бы предположил, что на проде кроме вашего пакета кто-то ещё работает...
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
06.02.2020, 13:25
    #39923366
MadBox
MadBox
Гость
Скрыть профиль Поместить в игнор-лист Сообщения автора в теме
Анализ трассировочных файлов. Источник возникновения ожидания
xtender, спасибо, пораскинул мозгами и решил идти по этому пути (продложить оптимизацию). Кроме увеличенной нагрузки I/O на диски выявили что идет конкуренция за PGA, на проде еще параллельно порядком пользовательских сессий из BI среды.
...
Рейтинг: 0 / 0
| Ответить | Цитировать | Написать  
Форумы / Oracle [игнор отключен] [закрыт для гостей] / Анализ трассировочных файлов. Источник возникновения ожидания / 6 сообщений из 6, страница 1 из 1
Целевая тема:
Создать новую тему:
Автор:
Найденые пользователи ...
Разблокировать пользователей ...
Читали тему (0):
Читали форум (0):
Пользователи онлайн (0):
Польз. соглашение
созд. / загр.: 422ms
Закрыть
start [/forum/topic.php?fid=52&mobile=1&tid=1881582]:
 0ms
get settings:
 9ms
get forum list:
 14ms
check forum access:
 5ms
check topic access:
 5ms
track hit:
 55ms
get topic data:
 12ms
get forum data:
 3ms
get page messages:
 47ms
get tp. blocked users:
 2ms
others: 270ms
total:  422ms
x
x
Закрыть


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