Forum Stats

  • 3,855,180 Users
  • 2,264,466 Discussions
  • 7,905,913 Comments

Discussions

GGS ERROR 190 on Extract

445461
445461 Member Posts: 8
edited Dec 8, 2010 3:51PM in GoldenGate
Hi all,

Does anyone meet this problem before?


[[email protected] ogg]$ tail -f ggserr.log
2010-12-06 16:51:57 GGS INFO Z0-016 Oracle GoldenGate Capture for Oracle, pri_ext.prm: Default thread stack size: 2093056.
2010-12-06 16:51:58 GGS INFO 310 Oracle GoldenGate Capture for Oracle, pri_ext.prm: EXTRACT PRI_EXT started.
2010-12-06 16:51:58 GGS INFO 112 Oracle GoldenGate Capture for Oracle, pri_ext.prm: Recovery initialization completed for target file ./dirdat/lt000005, at RBA 946.
2010-12-06 16:51:58 GGS INFO Z0-05M Oracle GoldenGate Capture for Oracle, pri_ext.prm: Output file ./dirdat/lt is using format RELEASE 10.4.
2010-12-06 16:51:58 GGS INFO 224 Oracle GoldenGate Capture for Oracle, pri_ext.prm: Rolling over remote file ./dirdat/lt000005.
2010-12-06 16:51:58 GGS INFO 112 Oracle GoldenGate Capture for Oracle, pri_ext.prm: Recovery completed for target file ./dirdat/lt000006, at RBA 946.
2010-12-06 16:51:58 GGS INFO 112 Oracle GoldenGate Capture for Oracle, pri_ext.prm: Recovery completed for all targets.
2010-12-06 16:52:22 GGS ERROR 190 Oracle GoldenGate Capture for Oracle, pri_ext.prm: error 22 (Invalid argument) opening redo log /dev/raw/raw14 for sequence 2388.
2010-12-06 16:52:23 GGS ERROR 190 Oracle GoldenGate Capture for Oracle, pri_ext.prm: error 22 (Invalid argument) opening redo log /dev/raw/raw12 for sequence 2079.
2010-12-06 16:52:23 GGS INFO 312 Oracle GoldenGate Capture for Oracle, pri_ext.prm: EXTRACT PRI_EXT stopped normally.

The source db is Oracle 9202 RAC on Redhat AS 3.

Best Regards,
Sean

Answers

  • 445461
    445461 Member Posts: 8
    RPT FILE:




    ***********************************************************************
    Oracle GoldenGate Capture for Oracle
    Version 10.4.0.88 Build 001
    Linux, x86, 32bit (optimized), Oracle 9 on Sep 10 2010 10:24:59

    Copyright (C) 1995, 2010, Oracle and/or its affiliates. All rights reserved.


    Starting at 2010-12-06 16:51:56
    ***********************************************************************

    Operating System Version:
    Linux
    Version #1 SMP Wed Aug 18 20:46:40 EDT 2004, Release 2.4.21-20.ELsmp
    Node: web1
    Machine: i686
    soft limit hard limit
    Address Space Size : unlimited unlimited
    Heap Size : unlimited unlimited
    File Size : unlimited unlimited
    CPU Time : unlimited unlimited

    Process id: 22958

    Description:

    ***********************************************************************
    ** Running with the following parameters **
    ***********************************************************************
    Extract pri_ext
    setenv (ORACLE_HOME="/oracle/product/9202")
    Set environment variable (ORACLE_HOME=/oracle/product/9202)
    SETENV (ORACLE_SID=orcl1)
    Set environment variable (ORACLE_SID=orcl1)
    userid ggs, password ***
    EXTTRAIL ./dirdat/lt, megabytes 10
    DISCARDFILE ./dirrpt/dm.dsc, append, megabytes 10
    NOCOMPRESSDELETES
    DYNAMICRESOLUTION
    TABLE test.a;

    CACHEMGR virtual memory values (may have been adjusted)
    CACHEBUFFERSIZE: 64K
    CACHESIZE: 2G
    CACHEBUFFERSIZE (soft max): 4M
    CACHEPAGEOUTSIZE (normal): 4M
    PROCESS VM AVAIL FROM OS (min): 2.93G
    CACHESIZEMAX (strict force to disk): 2.68G

    Database Version:
    Oracle9i Enterprise Edition Release 9.2.0.4.0 - Production
    PL/SQL Release 9.2.0.4.0 - Production
    CORE 9.2.0.3.0 Production
    TNS for Linux: Version 9.2.0.4.0 - Production
    NLSRTL Version 9.2.0.4.0 - Production

    Database Language and Character Set:
    NLS_LANG = "AMERICAN_AMERICA.ZHS16GBK"
    NLS_LANGUAGE = "AMERICAN"
    NLS_TERRITORY = "AMERICA"
    NLS_CHARACTERSET = "ZHS16GBK"

    2010-12-06 16:51:57 GGS INFO Z0-016 Default thread stack size: 2093056.

    2010-12-06 16:51:58 GGS INFO 112 Recovery initialization completed for target file ./dirdat/lt000005, at RBA 946.

    2010-12-06 16:51:58 GGS INFO Z0-05M Output file ./dirdat/lt is using format RELEASE 10.4.

    2010-12-06 16:51:58 GGS INFO 224 Rolling over remote file ./dirdat/lt000005.

    2010-12-06 16:51:58 GGS INFO 112 Recovery completed for target file ./dirdat/lt000006, at RBA 946.

    2010-12-06 16:51:58 GGS INFO 112 Recovery completed for all targets.

    ***********************************************************************
    ** Run Time Messages **
    ***********************************************************************

    2010-12-06 16:52:22.390 Redo thread 2:
    2010-12-06 16:52:22.390 Redo thread 2: Default destinations: count 1
    2010-12-06 16:52:22.393 Redo thread 2: </arch2log>
    2010-12-06 16:52:22.393 Redo thread 2:
    2010-12-06 16:52:22.393 Redo thread 2: Default format <w%t_%s.arc>
    2010-12-06 16:52:22.393 Redo thread 2:
    2010-12-06 16:52:22.393 Redo thread 2: No alternative log format for thread 2 was established
    2010-12-06 16:52:22.393 Redo thread 2:
    2010-12-06 16:52:22.393 Redo thread 2: Alternative formats from other threads: count 0
    212158385542393835 Redo Thread 2: thread abend: REDO_read_transaction( 1, (nil), error 22 (Invalid argument) opening redo log /dev/raw/raw14 for sequence 2388 )-> 501

    Source Context :
    SourceModule : [er.redo.ora.rtc]
    SourceID : [$Id: //depot/releases/OpenSys/v10.4.0/src/app/er/redo/oracle/rtc.c#3 $]
    SourceFunction : [RTC_producer]
    SourceLine : [1751]
    ThreadBacktrace : [5] elements
    : [ogg/ogg/extract(_ZN15CMessageContext16AddThreadContextEv+0x26) [0x828b106]]
    : [ogg/ogg/extract(_ZN15CMessageFactory13CreateMessageEP14CSourceContextjz+0x313) [0x827f083]]
    : [ogg/ogg/extract(_Z12RTC_producerPv+0x516) [0x826c426]]
    : [lib/i686/libpthread.so.0 [0x707e21]]
    : [lib/i686/libc.so.6(__clone+0x5a) [0x4133aa]]

    2010-12-06 16:52:22 GGS ERROR 190 error 22 (Invalid argument) opening redo log /dev/raw/raw14 for sequence 2388.
    2010-12-06 16:52:23.582 Redo thread 1:
    2010-12-06 16:52:23.582 Redo thread 1: Default destinations: count 1
    2010-12-06 16:52:23.582 Redo thread 1: </arch1log>
    2010-12-06 16:52:23.582 Redo thread 1:
    2010-12-06 16:52:23.582 Redo thread 1: Default format <w%t_%s.arc>
    2010-12-06 16:52:23.582 Redo thread 1:
    2010-12-06 16:52:23.582 Redo thread 1: No alternative log format for thread 1 was established
    2010-12-06 16:52:23.582 Redo thread 1:
    2010-12-06 16:52:23.582 Redo thread 1: Alternative formats from other threads: count 0
    212158385543582638 Redo Thread 1: thread abend: REDO_read_transaction( 1, (nil), error 22 (Invalid argument) opening redo log /dev/raw/raw12 for sequence 2079 )-> 501

    Source Context :
    SourceModule : [er.redo.ora.rtc]
    SourceID : [$Id: //depot/releases/OpenSys/v10.4.0/src/app/er/redo/oracle/rtc.c#3 $]
    SourceFunction : [RTC_producer]
    SourceLine : [1751]
    ThreadBacktrace : [5] elements
    : [ogg/ogg/extract(_ZN15CMessageContext16AddThreadContextEv+0x26) [0x828b106]]
    : [ogg/ogg/extract(_ZN15CMessageFactory13CreateMessageEP14CSourceContextjz+0x313) [0x827f083]]
    : [ogg/ogg/extract(_Z12RTC_producerPv+0x516) [0x826c426]]
    : [lib/i686/libpthread.so.0 [0x707e21]]
    : [lib/i686/libc.so.6(__clone+0x5a) [0x4133aa]]

    2010-12-06 16:52:23 GGS ERROR 190 error 22 (Invalid argument) opening redo log /dev/raw/raw12 for sequence 2079.

    ***********************************************************************
    * ** Run Time Statistics ** *
    ***********************************************************************


    Report at 2010-12-06 16:52:23 (activity since 2010-12-06 16:51:56)

    Output to ./dirdat/lt:

    No records extracted.


    ***********************************************************************
    * ** Run Time Statistics ** *
    ***********************************************************************


    Report at 2010-12-06 16:52:23 (activity since 2010-12-06 16:51:56)

    Output to ./dirdat/lt:

    No records extracted.


    CACHE OBJECT MANAGER statistics

    CACHE MANAGER VM USAGE
    vm current = 64K vm anon queues = 0
    vm anon in use = 64K vm file = 0
    vm used max = 64K ==> CACHE BALANCED

    CACHE CONFIGURATION
    cache size = 2G cache force paging = 2.68G
    buffer min = 64K buffer highwater = 4M
    pageout eligible size = 4M

    CACHE Transaction Stats
    trans active = 1 max concurrent = 2
    non-zero total = 38 trans total = 38

    CACHE File Caching
    disk current = 0 disk total = 0
    disk caching = 0 file cached = 0
    file retrieves = 0

    CACHE MANAGEMENT
    buffer links = 0 anon gets = 0
    forced unmaps = 0 cnnbl try = 0
    cached out = 0 force out = 0

    Allocation Request Distribution
    < 128B: 0
    128B: 0 1 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0

    Cached Transaction Size Distribution
    0: 37
    < 4K: 0
    4K: 0 0 | 16K: 0 0
    64K: 0 0 | 256K: 0 0
    1M: 0 0 | 4M: 0 0
    16M: 0 0 | 64M: 0 0
    256M: 0 0 | 1G: 0 0
    4G: 0 0 | 16G: 0 0
    64G: 0 0 | 256G: 0 0
    1T: 0 0 | 4T: 0 0
    16T: 0 0 | 64T: 0 0
    256T: 0 0 |1024T: 0 0


    QUEUE Statistics:
    num queues = 15 default index = 0
    cur len = 0 max len = 0
    q vm current = 0 vm max = 0
    q hits = 0 q misses = 1

    queue size q hits curlen maxlen cannibalized
    0 64K 0 0 0 0
    1 128K 0 0 0 0
    2 256K 0 0 0 0
    3 512K 0 0 0 0
    4 1M 0 0 0 0
    5 2M 0 0 0 0
    6 4M 0 0 0 0
    7 8M 0 0 0 0
    8 16M 0 0 0 0
    9 32M 0 0 0 0
    10 64M 0 0 0 0
    11 128M 0 0 0 0
    12 256M 0 0 0 0
    13 512M 0 0 0 0
    14 1G 0 0 0 0

    ================================================================================
    CACHE POOL #0
    POOL INFO group: pri_ext id: p22958_Redo Thread 2 instance: 2 tid: 0xc004
    trans active = 0 trans concurrent (max) = 0
    trans total = 0 (0 )
    flag = 0x00000003
    highestCSN = (wrap=0, base=0) valid = 0
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 0 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0

    ================================================================================
    CACHE POOL #1
    POOL INFO group: pri_ext id: p22958_Redo Thread 1 instance: 1 tid: 0x8003
    trans active = 1 trans concurrent (max) = 2
    trans total = 38 (38 )
    flag = 0x00000003
    highestCSN = (wrap=2641, base=4073000500) valid = 1
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 1 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0

    ================================================================================
    CACHE POOL #2
    POOL INFO group: pri_ext id: p22958_BLOB instance: 0 tid: (nil)
    trans active = 0 trans concurrent (max) = 0
    trans total = 0 (0 )
    flag = 0x00000003
    highestCSN = (wrap=0, base=0) valid = 0
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 0 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0


    QUEUE Statistics:
    num queues = 15 default index = 0
    cur len = 0 max len = 0
    q vm current = 0 vm max = 0
    q hits = 0 q misses = 1

    queue size q hits curlen maxlen cannibalized
    0 64K 0 0 0 0
    1 128K 0 0 0 0
    2 256K 0 0 0 0
    3 512K 0 0 0 0
    4 1M 0 0 0 0
    5 2M 0 0 0 0
    6 4M 0 0 0 0
    7 8M 0 0 0 0
    8 16M 0 0 0 0
    9 32M 0 0 0 0
    10 64M 0 0 0 0
    11 128M 0 0 0 0
    12 256M 0 0 0 0
    13 512M 0 0 0 0
    14 1G 0 0 0 0

    ================================================================================
    CACHE POOL #0
    POOL INFO group: pri_ext id: p22958_Redo Thread 2 instance: 2 tid: 0xc004
    trans active = 0 trans concurrent (max) = 0
    trans total = 0 (0 )
    flag = 0x00000003
    highestCSN = (wrap=0, base=0) valid = 0
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 0 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0

    ================================================================================
    CACHE POOL #1
    POOL INFO group: pri_ext id: p22958_Redo Thread 1 instance: 1 tid: 0x8003
    trans active = 1 trans concurrent (max) = 2
    trans total = 38 (38 )
    flag = 0x00000003
    highestCSN = (wrap=2641, base=4073000500) valid = 1
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 1 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0

    ================================================================================
    CACHE POOL #2
    POOL INFO group: pri_ext id: p22958_BLOB instance: 0 tid: (nil)
    trans active = 0 trans concurrent (max) = 0
    trans total = 0 (0 )
    flag = 0x00000003
    highestCSN = (wrap=0, base=0) valid = 0
    last error = (0=<none>)

    Allocation Request Distribution
    < 128B: 0
    128B: 0 0 | 512B: 0 0
    2K: 0 0 | 8K: 0 0
    32K: 0 0 | 128K: 0 0
    512K: 0 0 | 2M: 0 0
    8M: 0 0 | 32M: 0 0
    128M: 0 0 | 512M: 0 0
    2G: 0 0 | 8G: 0
  • User152973
    User152973 Member Posts: 148
    Hi selin,

    Is archive log mode enabled at the database level? If not, please enable it. I think that solves your issue.

    Thanks.
  • user11986736
    user11986736 Member Posts: 9 Blue Ribbon
    Your redo logs seem to be residing on raw devices! Try adding TRANLOGOPTIONS RAWDEVICEOFFSET 0 to the extract parameter file.

    The suggestion is based on an earlier recommendation made by Joe.

    Satish
This discussion has been closed.