OGG的extract進程checkpoint時間點回到1988-01-0100:00:00故障處理

Extract進程（SEXTR01）狀態為running，但是Lag at Chkpt卻達到5個多小時，且時間一直在增長，根本就不抽取新日志，狀態信息如下

1、故障現象
Extract進程（SEXTR01）狀態為running，但是Lag at Chkpt卻達到5個多小時，且時間一直在增長，根本就不抽取新日志，狀態信息如下：

GGSCI (caXXadgdb) 21> info all

Program Status Group Lag at Chkpt Time Since Chkpt

MANAGER RUNNING

EXTRACT RUNNING DPEYWGL 00:00:00 00:00:04

EXTRACT RUNNING SEXTR01 05:14:58 00:00:03

2、故障分析
2.1 debug一下進程狀態信息
GGSCI (caXXadgdb) 23> info sextr01, showch debug

EXTRACT SEXTR01 Last Started 2015-01-25 22:51 Status RUNNING

Checkpoint Lag 05:21:26 (updated 00:00:04 ago)

Log Read Checkpoint Oracle Redo Logs

2015-01-25 18:05:16 Thread 1, Seqno 55, RBA 611373536

SCN 3126.1366562 (13426204423758)

Log Read Checkpoint Oracle Redo Logs

1988-01-01 00:00:00 Thread 2, Seqno 4016, RBA 1024

SCN 44527.110828288 (191242119617280)

Current Checkpoint Detail:

Read Checkpoint #1

……

Read Checkpoint #2

Oracle Threaded Redo Log

Startup Checkpoint (starting position in the data source):

Thread #: 2

Sequence #: 4015

RBA: 0984080

Timestamp: 2015-01-25 18:03:51.000000

SCN: 3126.136602577 (13426204369873)

Redo File: +DGROUP1/caXXdb/onlinelog/group_15.267.799673791

Recovery Checkpoint (position of oldest unprocessed transaction in the data source):

Thread #: 2

Sequence #: 4015

RBA: 0984080

Timestamp: 2015-01-25 18:03:51.000000

SCN: 3126.136602577 (13426204369873)

Redo File: +DGROUP1/caXXdb/onlinelog/group_15.267.799673791

Current Checkpoint (position of last record read in the data source):

Thread #: 2

Sequence #: 4016

RBA: 1024

Timestamp: 1988-01-01 00:00:00.000000

SCN: 44527.110828288 (191242119617280)

Redo File: +DGROUP1/caXXdb/onlinelog/group_16.266.799673793

……

從這里發現一個不可思議的問題：

Thread #: 2的current checkpoint 的timestamp竟然是1988-01-0100:00:00.000000，SCN的十進制數，竟然為191242119617280，遠遠大于當前數據庫的SCN，多出一位數。

時間倒退，SCN號反而大漲，時間還那么有個性。這個問題挺有意思。

2.2 view extract進程的report
使用view report sextr01查看進程的報告，提示有ERROR，thread#2 4016 archive log 不能打開。（由于筆者沒有將信息復制下來，所以當時的狀態只能這么描述了），但是4016號archivelog實際物理文件是存在的，而且權限正常。

2.3 將進程停止下來，showch其狀態
GGSCI (calladgdb) 24>stop sextr01

GGSCI (calladgdb) 25> info SEXTR01, showch

EXTRACT SEXTR01 Last Started 2015-01-26 01:00 Status STOPPED

Checkpoint Lag 06:56:55 (updated 00:00:58 ago)

Log Read Checkpoint Oracle Redo Logs

2015-01-25 18:05:16 Thread 1, Seqno 55, RBA 611373536

SCN 3126.1366562 (13426204423758)

Log Read Checkpoint Oracle Redo Logs

1988-01-01 00:00:00 Thread 2, Seqno 4016, RBA 1024

SCN 44527.110828288 (191242119617280)

Current Checkpoint Detail:

Read Checkpoint #1

……

Read Checkpoint #2

Oracle Threaded Redo Log

Startup Checkpoint (starting position in the data source):

Thread #: 2

Sequence #: 4015

RBA: 0984080

Timestamp: 2015-01-25 18:03:51.000000

SCN: 3126.136602577 (13426204369873)

Redo File: +DGROUP1/caXXdb/onlinelog/group_15.267.799673791

Recovery Checkpoint (position of oldest unprocessed transaction in the data source):

Thread #: 2

Sequence #: 4015

RBA: 0984080

Timestamp: 2015-01-25 18:03:51.000000

SCN: 3126.136602577 (13426204369873)

Redo File: +DGROUP1/caXXdb/onlinelog/group_15.267.799673791

Current Checkpoint (position of last record read in the data source):

Thread #: 2

Sequence #: 4016

RBA: 1024

Timestamp: 1988-01-01 00:00:00.000000

SCN: 44527.110828288 (191242119617280)

Redo File: +DGROUP1/caXXdb/onlinelog/group_16.266.799673793

……

聲明：本網頁內容旨在傳播知識，若有侵權等問題請及時與本網聯系，我們將在第一時間刪除處理。TEL:177 7030 7066 E-MAIL:11247931@qq.com