APAR status
Closed as program error.
Error description
When TSM client acceptor daemon with commMethod set to Tcp/Ip attempts to register itself with the z/OS server, the DoRegisterCad() function on server returns RC=500 ABORT_NOT_TCPIP for commMethod BPX-Tcp/Ip and causes the following errors in the client's dsmerror.log: . ANS1568E The CAD attempted to register it's tcpport and tcpip address without using the TCPCADADDRESS option. ANS1570E Registering this instance of the Cad with the server failed. Cad process continues. . Though the server does not issue any errors, the following information is seen in the activity log: . ANR0406I Session XX started for node XXXXXXXX (WinNT) (BPX-Tcp/Ip X.X.X.X(xxxx)). (SESSION: XX) . The problem is that BPX-Tcp/Ip is a valid connection protocol, but is not recognized as such in this scenario. . DIAG: Following is the relevant trace information from the TSM server with traceflags SESSION, SYSTIME, ADDMSG, TCPDATA, TCPERROR, TCPINFO, SOCKET enabled: . 09:39:39.000 [67][SMTRANS][1099]: Recv Verb: Session 9, Length=42, Size 12, Code=31600, Type=RegCadInfo. 09:39:39.000 [67][SMNODE][2569]: Received verb RegCadInfo on session TPR50(9). 09:39:39.000 [67][SMNODE][23250]: RegisterCad: Entry for TPR50(9) commMethod(BPX-Tcp/Ip) 09:39:39.000 [67][SMNODE][23343]: RegisterCad: setting resp rc [500] for TPR50(9) 09:39:39.000 [67][SMTRANS][748]: SmSendVerb: Verb RegCadResp - passing verbPtr dc2eff8 and encPtr dc1dff8. 09:39:39.000 [67][SMTRANS][775]: SmSendVerb: Verb RegCadResp - sendPtr dc2eff8 after encrypt with original len 33 and size 12 - newLen 0. 09:39:39.000 [67][SMTRANS][824]: Send Verb: Session 9, Length=33, Code=31700, Type=RegCadResp, verbPtr dc2eff8. 09:39:39.000 [67][SMNODE][23373]: RegisterCad: Exit with respRc(500) rc[39] sendRc(0) for TPR50(9) 09:39:39.000 [67][SMTRANS][1258]: Push Verb: Session 9, Length=6, Code=0013, Type=EndTxn. 09:39:39.000 [67][SMTRANS][922]: Recv Pushed Verb: Session 9, Length=6, Code=0013, Type=EndTxn. 09:39:39.000 [67][SMNODE][2569]: Received verb EndTxn on session TPR50(9). Following is the relevant trace information from the TSM client with the SERVICE traceflag enabled: . 5/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (2628): COMMUNICATIONS OPTIONS 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x01=File ) commmethod TCP/IP 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x01=File ) tcpserveraddress 9.43.249.161 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x01=File ) tcpport 1,534 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpadminport 0 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpbuffsize 32,768 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpwindowsize 64,512 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpclientaddress 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpclientport 1,501 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpdebug No 05/01/2007 10:40:57.092 [002268] [3988] : optservices.cpp (6433): SrvCanOvrRde(N) Src(0x00=Default) tcpnodelay Yes ---------------------------------------------------- 05/01/2007 10:40:57.102 [002268] [3988] : cuauth.cpp (1292): Sending a RegisterCad verb: hlAddress = llAddress = 1187 05/01/2007 10:40:57.102 [002268] [3988] : session.cpp (3258): Send Verb: Length: 42 Code: 00031600 Type: RegisterCad 05/01/2007 10:40:57.102 [002268] [3988] : commtcp.cpp (1968): TcpWrite: 42 bytes written of 42 requested. 05/01/2007 10:40:57.102 [002268] [3988] : session.cpp (3295): Sent Verb: Length: 42 Code: 00031600 Type: RegisterCad-> 05/01/2007 10:40:57.102 [002268] [3988] : session.cpp (2852): Recv Verb: ... 05/01/2007 10:40:57.102 [002268] [3988] : commtcp.cpp (1593): TcpRead: Upper level requested 4 bytes 05/01/2007 10:40:57.102 [002268] [3988] : commtcp.cpp (2042): TcpFlush: writebuff(00000000), bufflen(0), lock(00D7DC38) 05/01/2007 10:40:57.102 [002268] [3988] : commtcp.cpp (2248): TcpFlush: 46 bytes written on socket 716. 05/01/2007 10:40:57.102 [002268] [3988] : commtcp.cpp (1711): TcpRead: Issuing recv for 4 bytes. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1827): TcpReadAvailable: 4 bytes read. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1632): TcpRead: 4 bytes read of 4 requested. 05/01/2007 10:40:57.142 [002268] [3988] : session.cpp (2901): sessRecvVerb(): length=0000, verb=08, magic=a5 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1593): TcpRead: Upper level requested 8 bytes 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1711): TcpRead: Issuing recv for 8 bytes. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1827): TcpReadAvailable: 8 bytes read. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1632): TcpRead: 8 bytes read of 8 requested. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1593): TcpRead: Upper level requested 21 bytes 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1711): TcpRead: Issuing recv for 21 bytes. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1827): TcpReadAvailable: 21 bytes read. 05/01/2007 10:40:57.142 [002268] [3988] : commtcp.cpp (1632): TcpRead: 21 bytes read of 21 requested. 05/01/2007 10:40:57.142 [002268] [3988] : session.cpp (2982): Recv Verb: 05/01/2007 10:40:57.142 [002268] [3988] : session.cpp (2983): Length: 33 Code: 00031700 Type: <- RegisterCadResp 05/01/2007 10:40:57.142 [002268] [3988] : cuauth.cpp (1328): Contents of verb (0x31700) RegisterCadResp, length: 33: regCadRespVer : 0x1 opRC : 500 05/01/2007 10:40:57.142 [002268] [3988] : cuauth.cpp (1333): cuRegeisterCadResp: received response for cad registration rc 500 . Initial Impact: Low . Additional Keywords: z/OS DoRegisterCad MVS
Local fix
N/A
Problem summary
**************************************************************** * USERS AFFECTED: All TSM Server versions on the z/OS * * platform. * **************************************************************** * PROBLEM DESCRIPTION: See ERROR DESCRIPTION. * **************************************************************** * RECOMMENDATION: Apply fixing level when available. This * * problem is currently projected to be fixed * * in levels 5.3.6 and 5.4.2 Note that this * * is subject to change at the discretion of * * IBM. * **************************************************************** *
Problem conclusion
The problem has been fixed.
Temporary fix
Comments
APAR Information
APAR number
PK50284
Reported component name
TSM FOR OS/390
Reported component ID
5698ISMVS
Reported release
542
Status
CLOSED PER
PE
NoPE
HIPER
NoHIPER
Special Attention
NoSpecatt / Xsystem
Submitted date
2007-08-01
Closed date
2007-08-01
Last modified date
2007-08-01
APAR is sysrouted FROM one or more of the following:
APAR is sysrouted TO one or more of the following:
Fix information
Fixed component name
TSM FOR OS/390
Fixed component ID
5698ISMVS
Applicable component levels
R542 PSY
UP
[{"Business Unit":{"code":"BU058","label":"IBM Infrastructure w\/TPS"},"Product":{"code":"SSGSG7","label":"Tivoli Storage Manager"},"Component":"","ARM Category":[],"Platform":[{"code":"PF025","label":"Platform Independent"}],"Version":"542","Edition":"","Line of Business":{"code":"LOB26","label":"Storage"}}]
Document Information
Modified date:
01 August 2007