Working with DL/I call trace information


Log Analyzer can help you analyze the information that is returned from a PSB trace of DL/I calls.

Because a large volume of trace information can be generated, you typically do not want to request tracing of DL/I calls from all PSBs. However, if you can identify a specific PSB that is involved in problem transactions, you can request a call trace for the PSB, run the transactions, turn off the trace, and then analyze the logs with Log Analyzer. Log Analyzer associates the DL/I call trace (type 5F) records with LUOWs to provide a comprehensive view of the DL/I calls and transaction activities.

To work with DL/I call trace information in 

Log Analyzer

  1. Start tracing the calls that are issued by a PSB, and allow transactions to run until a sufficient number of calls have been issued by the PSB.Type 5F records are written to the IMS log during this time range. You can make a note of the time range so that you can specify it in the Log Analyzer analysis job.

    For more information about trace commands, see the documentation for your version of IMS.

  2. Stop tracing the calls for the PSB.
  3. Specify the JCL for the analysis job as follows:

    • You can specify the START and STOP keywords on the INTERVAL control statement and specify the time range of the traced calls.
    • Specify the SELECT keyword on the FILTER control statement to specify the PSB name.
    • You can specify the LOGRECORDCODES keyword on the FILTER control statement to specify the record types to include and exclude in LUOWs. Log Analyzer automatically includes type 5F records in transaction LUOWs.
    • Specify the LUOWDETAIL keyword on the REPORTS control statement to request the LUOW Detail report. You can specify other REPORTS keywords if you want to view other reports, but the LUOW Detail report contains the information from the type 5F records.

    The following example shows analysis job control statements:

    InformationANALYZE ...
    INTERVAL START=yyyyddd/hhmmssth
             STOP=yyyyddd/hhmmssth
    FILTER                                           
       LOGRECORDCODES=(1,3,7,8,18,3*,5*,¬59)    
       SELECT=PSB=DFSSAM04
    REPORTS ...
       LUOWDETAIL=(ALL)
    ...

  4. Execute the analysis job.
  5. View the reports.The LUOW Detail report displays the type 5F records in context with other records that provide information about the activities that occurred during the LUOW:

     2008-051                                         Log Analyzer for IMS V1.2.00.00                       Page 1
                                                     LUOW Detail     (LUOWDET)  R=9                                       
    LOG time span: FROM 2008-045 15:42:05.5 TO 2008-045 16:15:30.3                                                        
     LUOW 0000047 Log recs=0000025 Rec tkns=0000001 UOW-1=PR9F    C1F3579148297B26                                        
     Code  Type   Log Sequence Num -----Time------  Variable--------------------------------------------------------------
     01    MSGIN     0000000000181 15:44:05.642956  oims=PR9F dims=PR9F origin=RIP0002  dest=DLETPART userid=RIP0002      
                                                    drrn=0400002E                                                         
     35    ENQ       0000000000182 15:44:05.642968  dest=DLETPART drrn=0400002E                                           
     08    APPLC     0000000000183 15:44:05.643061  recovery token=PR9F    0000000200000000 tran=DLETPART                 
     5607  TPCP      0000000000184 15:44:05.643061  (start unit-of-recovery) recovery token=PR9F    0000000200000000      
                                                    psb=DFSSAM04                                                          
     31    GUDLI     0000000000185 15:44:05.643082  recovery token=PR9F    0000000200000000 dest=DLETPART drrn=0400002E   
     5F    DL/I      0000000000188 15:44:05.647343  psb=DFSSAM04 pcb=DI21PART type=call function=GU                       
                                                    ssa=PARTROOT(PARTKEY  =02BB960C10       )                             
     5F    DL/I      0000000000189 15:44:05.647935  psb=DFSSAM04 pcb=DI21PART type=ret  seg level=01 status=              
                                                    seg name=PARTROOT                                                     
     5F    DL/I      000000000018A 15:44:05.648033  psb=DFSSAM04 pcb=DI21PART type=call function=GNP                      
                                                    ssa=PARTROOT(PARTKEY  =02BB960C10       )
     5F    DL/I      000000000018D 15:44:05.648177  psb=DFSSAM04 pcb=DI21PART type=ret  seg level=01 status=           
                                                    seg name=PARTROOT                                                  
     5F    DL/I      000000000018E 15:44:05.648233  psb=DFSSAM04 pcb=DI21PART type=call function=DLET                  
     5050  DBDSG     000000000018F 15:44:05.648258  dbd=DI21PART dsid=00001 recovery token=PR9F    0000000200000000    
     5050  DBDSG     0000000000190 15:44:05.648271  dbd=DI21PART dsid=00001 recovery token=PR9F    0000000200000000    
     5F    DL/I      0000000000191 15:44:05.648575  psb=DFSSAM04 pcb=DI21PART type=ret  seg level=01 status=           
                                                    seg name=PARTROOT                                                  
     03    MSGOT     0000000000192 15:44:05.650224  oims=PR9F dims=PR9F origin=RIP0002  dest=RIP0002  userid=RIP0002   
                                                    drrn=0400002F                                                      
     35    ENQ       0000000000193 15:44:05.650236  recovery token=PR9F    0000000200000000 dest=RIP0002  drrn=0400002F
     37    XFER      0000000000194 15:44:05.650245  (no formatted data)                                                
     37    XFER      0000000000195 15:44:05.650261  oims=PR9F dims=PR9F                                                
     33    FREE      0000000000196 15:44:05.650272  oims=PR9F dims=PR9F drrn=0400002E                                  
     5612  TPCP      0000000000197 15:44:05.650291  (end phase2 syncpoint) recovery token=PR9F    0000000200000000     
                                                    psb=DFSSAM04 userid=RIP0002                                        
     07    APPLT     000000000019A 15:44:05.650329  psb=DFSSAM04 tran=DLETPART recovery token=PR9F    0000000200000000
     31    GUCOM     000000000019B 15:44:05.650414  dest=RIP0002  drrn=0400002F                                        
     36    MGDEQ     000000000019C 15:44:05.651428  oims=PR9F dims=PR9F drrn=0400002F                                  
     33    FREE      000000000019D 15:44:05.651439  oims=PR9F dims=PR9F drrn=0400002F

 

Tip: For faster searching, add an asterisk to the end of your partial query. Example: cert*

BMC AMI Log Analyzer for IMS 1.7