15.00 - Sample Output - Parallel Data Pump

Teradata Parallel Data Pump Reference

prodname
Parallel Data Pump
vrm_release
15.00
category
Programming Reference
featnum
B035-3021-034K

Sample Output

The following is a sample output when ".DEBUG TRACE LOAD" is specified in TPump script:

 ========================================================================
=                                                                      =
=          Teradata Parallel Data Pump Utility    Release 15.00.00.000 =
=          Platform WIN32                                              =
=          PID      4028                                               =
=                                                                      =
========================================================================
=                                                                      =
=     Copyright 1997-2014 Teradata Corporation. ALL RIGHTS RESERVED.   =
=                                                                      =
========================================================================
**** 21:02:50 UTY2411 Processing start date: THU MAR 06, 2014
     ========================================================================
=                                                                      =
=          Logon/Connection                                            =
=                                                                      =
========================================================================
0001 .DEBUG TRACE LOAD;
0002 .LOGTABLE mylogtable;
0003 .LOGON <tdpid>/<userid>,;
**** 21:02:53 UTY8400 Teradata Database Release: 15.00.00.000
**** 21:02:53 UTY8400 Teradata Database Version: 15.00.00.000
**** 21:02:53 UTY8400 Default character set: ASCII                         
**** 21:02:53 UTY8400 Current RDBMS has UDT support
**** 21:02:53 UTY8400 Current RDBMS has Large Decimal support
**** 21:02:53 UTY8400 Maximum supported buffer size: 1M
**** 21:02:53 UTY8400 Upsert supported by RDBMS server
**** 21:02:53 UTY8400 Data Encryption supported by RDBMS server
**** 21:02:53 UTY8400 Array Support supported by RDBMS server
**** 21:02:53 UTY8400 Statement Independence supported by RDBMS server
**** 21:02:53 UTY6211 A successful connect was made to the RDBMS.
**** 21:02:53 UTY6217 Logtable '<Database name>.mylogtable' has been created.
     ========================================================================
=                                                                      =
=          Processing Control Statements                               =
=                                                                      =
========================================================================
0004 DROP TABLE testtbl;
**** 21:02:53 UTY1016 'DROP' request successful.
0005 DROP TABLE myerrtbl;
**** 21:02:54 UTY1016 'DROP' request successful.
0006 /*************************************************/
     /* Create and insert table                       */
     /*************************************************/
     CREATE MULTISET TABLE testtbl, FALLBACK (
       U1 varchar(5),
       U2 varchar(5))
      PRIMARY INDEX(U1);
**** 21:02:54 UTY1016 'CREATE' request successful.
 
0007 .BEGIN LOAD 
                SESSIONS 2 1
                ERRORTABLE myerrtbl append
                ERRLIMIT 200
                TENACITY 0
                PACK   5
                ARRAYSUPPORT ON
     ;
     ========================================================================
=                                                                      =
=          Processing TPump Statements                                 =
=                                                                      =
========================================================================
 
0008 .LAYOUT LAY1A;
0009 .FIELD U1  *  varchar(5) ;
0010 .FIELD U2  *  varchar(5) ;
0011 .DML LABEL LABELA;
0012 INSERT INTO testtbl VALUES (:U1,:U2);
0013 .IMPORT INFILE VARDATAa 
                    FORMAT VARTEXT ' ' NOSTOP 
                    LAYOUT LAY1A
                    APPLY LABELA;
 
0014 .END LOAD;
**** 21:02:54 UTY6609 Starting to log on sessions...
     StartConn: connect DBCHCL status: 0
     StartConn: connect DBCHCL status: 0
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     DBCAreaPtr->fet_parcel_flavor is 8
     DBCAreaPtr->fet_parcel_flavor is 12
     Logged on session[0] 14 TDPSess = 21934
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     DBCAreaPtr->fet_parcel_flavor is 8
     DBCAreaPtr->fet_parcel_flavor is 12
     Logged on session[1] 15 TDPSess = 21935
**** 21:02:54 UTY6610 Logged on 2 sessions.
     ========================================================================
=                                                                      =
=          TPump Import(s) Beginning                                   =
=                                                                      =
========================================================================
**** 21:02:54 UTY6630 Options in effect for following TPump Import(s):
     .       Tenacity:    sessions must successfully connect on first try.
     .   Max Sessions:    2 session(s).
     .   Min Sessions:    1 session(s).
     .     Checkpoint:    15 minute(s).
     .       Errlimit:    200 rejected record(s).
     .   Restart Mode:    ROBUST.
     .  Serialization:    OFF.
     .        Packing:    5 Statements per Request.
     .   StartUp Rate:    UNLIMITED Statements per Minute.
     StmtGenTest buffer len: 74
     StmtGenTest request len: 73
     Preparing SQL: 'USING(U1 VARCHAR(5),U2 VARCHAR(5)) INSERT INTO testtbl
     VALUES (:U1,:U2);
     '
     StmtGenMacro buffer len: 1473
     StmtGenMacro request len: 161
     macro: CREATE MACRO <Database name>.M20140306_210255_08178_001_001(U1 VARCHAR(5)
     CHARACTER SET LATIN,U2 VARCHAR(5) CHARACTER SET LATIN) AS (INSERT INTO
     testtbl VALUES (:U1,:U2);
     );
     Testing pack factor 5
     StmtGenTemplate nOneStmtUBytes: 30
     StmtGenTemplate nUsingBytes: 37
     StmtGenTemplate nStmtBytes: 51
     StmtGenTemplate buffer len: 89
     StmtGenTemplate request len: 88
     Sqllen: 88, Datalen: 75, Maxlen: 1048472
     TESTPACKFACTOR: extra_cli_overhead: 40
     Partition DEFAULT PARTITION using request template
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 53 byte request: 'exec TPumpMacro.ImportCreate('<Database name>', 'mylogtable',
     1);'
     Using 0 byte(s) of data: ''
     Ctx->ReqId updated to o_req_id = 2
     DBCAreaPtr->fet_parcel_flavor is 9
**** 21:02:55 UTY8802 WARNING: Rate Monitoring turned off - database TPumpMacro
     does not exist.
**** 21:02:55 UTY6608 Import 1 begins.
     DEFAULT PARTITION session 0 Buckets: 1 Total Buckets: 1
     Sending Bucket on DEFAULT PARTITION session 0
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 3 byte request: 'BT;'
     Using 0 byte(s) of data: ''
     Ctx->ReqId updated to o_req_id = 3
     DEFAULT PARTITION session 1 Buckets: 1 Total Buckets: 2
     Sending Bucket on DEFAULT PARTITION session 1
     token = 1, i_sess_id = Ctx->Id = 15
     Sending 3 byte request: 'BT;'
     Using 0 byte(s) of data: ''
     Ctx->ReqId updated to o_req_id = 2
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 3
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 88 byte request: 'USING(AA1 VARCHAR(5),AA2 VARCHAR(5)) EXEC
     <Database name>.M20140306_210255_08178_001_001(:AA1,:AA2);'
     using_data_count = 5, data starting address=00f24d80
     Using data record # 0, len = 15, address = 00f24bf0 
     Using data: '000500313233343505003233343536'
     Using data record # 1, len = 15, address = 00f24bff 
     Using data: '000500313233343505003233343536'
     Using data record # 2, len = 15, address = 00f24c0e 
     Using data: '000500313233343505003233343536'
     Using data record # 3, len = 15, address = 00f24c1d 
     Using data: '000500313233343505003233343536'
     Using data record # 4, len = 15, address = 00f24c2c 
     Using data: '000500313233343505003233343536'
     Ctx->ReqId updated to o_req_id = 4
     DEFAULT PARTITION session 0 Buckets: 1 Total Buckets: 3
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 1
     Corresponding session has status = 1, Ctx->Id = 15, Ctx->ReqId = 2
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     token = 1, i_sess_id = Ctx->Id = 15
     Sending 88 byte request: 'USING(AA1 VARCHAR(5),AA2 VARCHAR(5)) EXEC
     <Database name>.M20140306_210255_08178_001_001(:AA1,:AA2);'
     using_data_count = 5, data starting address=00ee2028
     Using data record # 0, len = 15, address = 00f24f80 
     Using data: '000500313233343505003233343536'
     Using data record # 1, len = 15, address = 00f24f8f 
     Using data: '000500313233343505003233343536'
     Using data record # 2, len = 15, address = 00f24f9e 
     Using data: '000500313233343505003233343536'
     Using data record # 3, len = 15, address = 00f24fad 
     Using data: '000500313233343505003233343536'
     Using data record # 4, len = 15, address = 00f24fbc 
     Using data: '000500313233343505003233343536'
     Ctx->ReqId updated to o_req_id = 3
     Hit End of file
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 1
     Corresponding session has status = 1, Ctx->Id = 15, Ctx->ReqId = 3
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     sending ET on DEFAULT PARTITION session 1
     token = 1, i_sess_id = Ctx->Id = 15
     Sending 188 byte request: 'Using (CP varbyte(30000), sessstats varbyte(36))
     Insert <Database name>.mylogtable (LogType, Seq, LoadImpSeq, LoadSrcSeq, LoadCPSeq,
     LoadCkpt, SessStats) values (306, 1, 1, 1, 1, :CP, :sessstats); ET;'
     Using 140 byte(s) of data:
     '64000600000001000000000000000200000101010000070000000100000000000000020000
     010101000008000000010000000000000002000001010100000900000001000000000000000
     2000001010100000A0000000100000000000000020000010101000024000100000005000000
     CDCDCDCD0500000000000000010000000F000000000000000F000000'
     Ctx->ReqId updated to o_req_id = 4
     Flushing backlog
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 1
     Corresponding session has status = 1, Ctx->Id = 15, Ctx->ReqId = 4
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     done on DEFAULT PARTITION session 1
     Flushing backlog
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 4
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     sending ET on DEFAULT PARTITION session 0
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 188 byte request: 'Using (CP varbyte(30000), sessstats varbyte(36))
     Insert <Database name>.mylogtable (LogType, Seq, LoadImpSeq, LoadSrcSeq, LoadCPSeq,
     LoadCkpt, SessStats) values (306, 1, 1, 1, 2, :CP, :sessstats); ET;'
     Using 140 byte(s) of data:
     '64000100000001000000000000000200000101010000020000000100000000000000020000
     010101000003000000010000000000000002000001010100000400000001000000000000000
     200000101010000050000000100000000000000020000010101000024000000000005000000
     CDCDCDCD050000000000000001000000000000000000000000000000'
     Ctx->ReqId updated to o_req_id = 5
     Flushing backlog
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 5
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     done on DEFAULT PARTITION session 0
     Flushing backlog
     Sending Bucket on DEFAULT PARTITION session 0
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 3 byte request: 'BT;'
     Using 0 byte(s) of data: ''
     Ctx->ReqId updated to o_req_id = 6
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 6
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 88 byte request: 'USING(AA1 VARCHAR(5),AA2 VARCHAR(5)) EXEC
     <Database name>.M20140306_210255_08178_001_001(:AA1,:AA2);'
     using_data_count = 1, data starting address=00ee24e8
     Using data record # 0, len = 15, address = 00ee2358 
     Using data: '000500313233343505003233343536'
     Ctx->ReqId updated to o_req_id = 7
     Flushing backlog
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 7
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     sending ET on DEFAULT PARTITION session 0
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 188 byte request: 'Using (CP varbyte(30000), sessstats varbyte(36))
     Insert <Database name>.mylogtable (LogType, Seq, LoadImpSeq, LoadSrcSeq, LoadCPSeq,
     LoadCkpt, SessStats) values (306, 1, 1, 1, 3, :CP, :sessstats); ET;'
     Using 60 byte(s) of data:
     '14000B0000000100000000000000020000010101000024000000000001000000CDCDCDCD01
     0000000000000001000000100000000000000010000000'
     Ctx->ReqId updated to o_req_id = 8
     Flushing backlog
     calling SessCheckT w/ timeout = 60
     WaitForResponse: Calling dbchwat
     WaitForResponse: Returned from dbchwat
     WaitForResponse returned token 0
     Corresponding session has status = 1, Ctx->Id = 14, Ctx->ReqId = 8
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 205
     DBCAreaPtr->fet_parcel_flavor is 11
     DBCAreaPtr->fet_parcel_flavor is 12
     done on DEFAULT PARTITION session 0
     Flushing backlog
**** 21:02:55 UTY6641 Since last chkpt., 11 recs. in, 11 stmts., 3 reqs
**** 21:02:55 UTY6647 Since last chkpt., avg. DBS wait time: 46.67
**** 21:02:55 UTY6612 Beginning final checkpoint...
**** 21:02:55 UTY6641 Since last chkpt., 11 recs. in, 11 stmts., 3 reqs
**** 21:02:55 UTY6647 Since last chkpt., avg. DBS wait time: 46.67
**** 21:02:55 UTY6607 Checkpoint Completes with 11 rows sent.
**** 21:02:55 UTY6642 Import 1 statements: 11, requests: 3
**** 21:02:55 UTY6643 Import 1 average statements per request: 3.67
**** 21:02:55 UTY6644 Import 1 average statements per record: 1.00
**** 21:02:55 UTY6645 Import 1 statements/session: avg. 5.50, min. 5.00, max.
     6.00
**** 21:02:55 UTY6646 Import 1 requests/session: average 1.50, minimum 1.00,
     maximum 2.00
**** 21:02:55 UTY6648 Import 1 DBS wait time/session: avg. 70.00, min. 62.00,
     max. 78.00
**** 21:02:55 UTY6649 Import 1 DBS wait time/request: avg. 50.50, min. 39.00,
     max. 62.00
**** 21:02:55 UTY1823 Import processing statistics
     .                                           IMPORT  1      Total thus far
     .                                           =========      ==============
     Candidate records considered:....                  11                  11
     Apply conditions satisfied:......                  11                  11
     Records logable to error table:..                   0                   0
     Candidate records rejected:......                   0                   0
**** Statistics for Apply Label : LABELA
     Type:                 I
     Database:             <Database name>
     Table or Macro Name:  testtbl
     Activity:             11
**** 21:02:55 UTY6677 Loading phase statistics 
              Elapsed time:   00:00:00:00(dd:hh:mm:ss)
              CPU time:       0.015625 Seconds
              MB/sec:         N/A
              MB/cpusec:      0.01056
**** 21:02:55 UTY0821 Error table <Database name>.myerrtbl is EMPTY, dropping table.
     token = 0, i_sess_id = Ctx->Id = 14
     Sending 53 byte request: 'exec TPumpMacro.DeleteImport('<Database name>', 'mylogtable',
     1);'
     Using 0 byte(s) of data: ''
     Ctx->ReqId updated to o_req_id = 9
     DBCAreaPtr->fet_parcel_flavor is 9
0015 .LOGOFF;
     ========================================================================
=                                                                      =
=          Logoff/Disconnect                                           =
=                                                                      =
========================================================================
**** 21:02:56 UTY6216 The restart log table has been dropped.
**** 21:02:56 UTY6212 A successful disconnect was made from the RDBMS.
**** 21:02:56 UTY2410 Total processor time used = '0.921875 Seconds'
     .       Start : 21:02:50 - THU MAR 06, 2014
     .       End   : 21:02:56 - THU MAR 06, 2014
     .       Highest return code encountered = '0'.
     getting out w/rc 0