I should have said ". the button pushes are carried through H.323 not TSAPI."

Also what is the Avaya Global Services ticket number (you used to get the avaya tech to investigate).

Hi John, Just getting started this morning. FE to come back today around 09:00- 09:30. Our Ticket info:
New tic # 16028338. Routed to Tier III for review, and then RFE dispatch....
and our engineer's name is Kevin Wright. Today's plan is to mirror the port out of the AES to determine what traffic is leaving it to the CLAN on the PBX. I tell you though, we are grabbing at straws since everything seems to be working based on the logs.

Further information. I have been able to pretty much take network issues out of the picture. I am able to run the TSTEST successfully from the same network segment that the Etalk servers are on, with no additional routes to/from the AES server. I am still concerned with the parsing warnings that we are receiving in the error logs that I listed below. Any ideas what those might be? Here is a chunk of the CSTA_trace log showing successful tests from both teh .248 (where the AES Lives) and the .17 (where the Etalk server lives)

10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: Login: QFINITI App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: value ACSOpenStream ::=
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: streamType stCsta,
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: serverID "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: loginID "qfiniti",
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: cryptPass '61580B1F2B8C1FA7C167D3A571AF28EBDB ...'H,
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: applicationName "TSTest",
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: level acsLevel1,
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: apiVer "TS2",
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: libVer "AES4.2.2 Build 451",
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: tsrvVer "4.2.2 Build 437"
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:28:01.987:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: value ACSClientHeartbeatEvent ::=
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: null NULL
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: value ACSOpenStreamConfEvent ::=
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: apiVer "ST2",
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: libVer "AES4.2.2 Build 451",
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: tsrvVer "4.2.2 Build 437",
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: drvrVer "4.2.2 Build 437"
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:28:01.995:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: value CSTAMakeCall ::=
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: callingDevice "9403",
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: calledDevice "9120"
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:28:01.997:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: value CSTAMakeCallConfEvent ::=
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: newCall
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: callID 11480,
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: deviceID "9403",
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: devIDType staticId
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:28:02.198:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: value CSTAClearConnection ::=
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: call
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: callID 11480,
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: deviceID "9403",
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: devIDType staticId
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:28:06.402:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: value CSTAClearConnectionConfEvent ::=
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: null NULL
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:28:06.450:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 37 Transport ID: 10.54.248.13
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: value ACSAbortStream ::=
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: null NULL
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:28:06.451:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: TSERVER Src: CLNT
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: Login: App Name: SessionID: 38 Transport ID: 10.54.17.59
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: InvokeID: 0
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: Message:
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: value ACSNameSrvRequest ::=
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: {
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: streamType stCsta
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0: }
10/07/2010 07:31:53.798:TSAPI:Thread 0x92ff4ba0:
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 38 Transport ID: 10.54.17.59
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: InvokeID: 0
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: value ACSNameSrvReply ::=
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: more FALSE,
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: list
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: serverAddr '0200041A0A36F8210000000000000000'H
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: },
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA-S#RCIINDTELNP11",
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: serverAddr '0200042A0A36F8210000000000000000'H
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: }
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: }
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0: }
10/07/2010 07:31:53.799:TSAPI:Thread 0x62b0ba0:
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: Login: App Name: SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: value ACSKeyRequest ::=
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: loginID "qfiniti"
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:53.899:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: InvokeID: 0
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: value ACSAuthReplyTwo ::=
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: objectID 0,
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: key '68E1230F996B85D6'H,
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: authInfo
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: {
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: authType needLoginIdAndPasswd,
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: authLoginID "qfiniti"
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: },
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: encodeType winNtLocal,
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: pipe ""
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0: }
10/07/2010 07:31:53.900:TSAPI:Thread 0x62b0ba0:
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: Login: QFINITI App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: value ACSOpenStream ::=
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: streamType stCsta,
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: serverID "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: loginID "qfiniti",
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: cryptPass '566074983A6EB391C613D30BB5864250D8 ...'H,
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: applicationName "TSTest",
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: level acsLevel1,
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: apiVer "TS2",
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: libVer "AES4.2.2 Build 451",
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: tsrvVer "4.2.2 Build 437"
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:53.901:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: value ACSClientHeartbeatEvent ::=
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: null NULL
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.909:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: value ACSOpenStreamConfEvent ::=
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: apiVer "ST2",
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: libVer "AES4.2.2 Build 451",
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: tsrvVer "4.2.2 Build 437",
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: drvrVer "4.2.2 Build 437"
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:31:53.910:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: value CSTAMakeCall ::=
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: callingDevice "9403",
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: calledDevice "9120"
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:53.910:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: value CSTAMakeCallConfEvent ::=
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: newCall
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: callID 11519,
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: deviceID "9403",
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: devIDType staticId
10/07/2010 07:31:54.160:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:31:54.161:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:31:54.161:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: InvokeID: 0
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: value CSTAClearConnection ::=
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: call
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: callID 11519,
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: deviceID "9403",
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: devIDType staticId
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:57.939:TSAPI:Thread 0x925f3ba0:
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: InvokeID: 0
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: value CSTAClearConnectionConfEvent ::=
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: {
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: null NULL
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0: }
10/07/2010 07:31:58.013:TSAPI:Thread 0x957f8ba0:
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: Login: qfiniti App Name: TSTest SessionID: 39 Transport ID: 10.54.17.59
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: value ACSAbortStream ::=
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: {
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: null NULL
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0: }
10/07/2010 07:31:58.014:TSAPI:Thread 0x925f3ba0:

Here's a CSTA trace showing a registration of station 3565. The weird part is the transport ID is 10.54.17.50....then there's a message regarding authtype - ned ID and password...then the transport ID changes to 127.0.0.1 and commands continue. I did not see this on the TSAPI test where the Transport ID stayed the same throughout the transaction.

10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: TSERVER Src: CLNT
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: Login: App Name: SessionID: 42 Transport ID: 10.54.17.50
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: InvokeID: 0
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: Message:
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: value ACSNameSrvRequest ::=
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: {
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: streamType stCsta
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0: }
10/07/2010 08:17:15.240:TSAPI:Thread 0x92ff4ba0:
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 42 Transport ID: 10.54.17.50
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: InvokeID: 0
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: value ACSNameSrvReply ::=
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: more FALSE,
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: list
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: serverAddr '0200041A0A36F8210000000000000000'H
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: },
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA-S#RCIINDTELNP11",
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: serverAddr '0200042A0A36F8210000000000000000'H
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.241:TSAPI:Thread 0x62b0ba0:
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: TSERVER Src: CLNT
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: Login: App Name: SessionID: 43 Transport ID: 10.54.17.50
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: InvokeID: 0
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: Message:
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: value ACSNameSrvRequest ::=
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: {
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: streamType stCsta
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0: }
10/07/2010 08:17:15.343:TSAPI:Thread 0x92ff4ba0:
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 43 Transport ID: 10.54.17.50
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: InvokeID: 0
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: value ACSNameSrvReply ::=
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: more FALSE,
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: list
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: serverAddr '0200041A0A36F8210000000000000000'H
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: },
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA-S#RCIINDTELNP11",
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: serverAddr '0200042A0A36F8210000000000000000'H
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.344:TSAPI:Thread 0x62b0ba0:
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: Login: App Name: SessionID: 44 Transport ID: 10.54.17.50
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: InvokeID: 1
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: value ACSKeyRequest ::=
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: {
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: loginID ""
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0: }
10/07/2010 08:17:15.358:TSAPI:Thread 0x925f3ba0:
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 44 Transport ID: 10.54.17.50
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: InvokeID: 1
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: value ACSAuthReplyTwo ::=
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: objectID 0,
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: key 'D7DBAEB65C6C0B25'H,
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: authInfo
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: authType needLoginIdAndPasswd,
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: authLoginID ""
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: },
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: encodeType winNtLocal,
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: pipe ""
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:17:15.358:TSAPI:Thread 0x62b0ba0:
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: TSERVER Src: CLNT
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: Login: App Name: SessionID: 45 Transport ID: 127.0.0.1
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: InvokeID: 1
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: Message:
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: value ACSNameSrvRequest ::=
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: {
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: streamType stCsta
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0: }
10/07/2010 08:22:18.282:TSAPI:Thread 0x92ff4ba0:
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 45 Transport ID: 127.0.0.1
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: InvokeID: 1
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: value ACSNameSrvReply ::=
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: more FALSE,
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: list
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: serverAddr '0200041A000000000000000000000000'H
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: },
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.282:TSAPI:Thread 0x62b0ba0: serverName "AVAYA#SWLINK1#CSTA-S#RCIINDTELNP11",
10/07/2010 08:22:18.283:TSAPI:Thread 0x62b0ba0: serverAddr '0200042A000000000000000000000000'H
10/07/2010 08:22:18.283:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:22:18.283:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:22:18.283:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:22:18.283:TSAPI:Thread 0x62b0ba0:
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: TSERVER Src: CLNT
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: Login: App Name: SessionID: 45 Transport ID: 127.0.0.1
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: Driver: TSERVER#NSRV#NSRV#RCIINDTELNP11
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: Message:
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: value ACSAbortStream ::=
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: {
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: null NULL
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0: }
10/07/2010 08:22:18.283:TSAPI:Thread 0x92ff4ba0:
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: Login: App Name: SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: InvokeID: 1
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: value ACSKeyRequest ::=
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: {
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: loginID "DMCCLCSUserDoNotModify"
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0: }
10/07/2010 08:22:18.319:TSAPI:Thread 0x925f3ba0:
10/07/2010 08:22:18.319:TSAPI:Thread 0x62b0ba0: TSERVER Src: DRVR
10/07/2010 08:22:18.319:TSAPI:Thread 0x62b0ba0: Login: App Name: SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:22:18.319:TSAPI:Thread 0x62b0ba0: InvokeID: 1
10/07/2010 08:22:18.319:TSAPI:Thread 0x62b0ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: Message:
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: value ACSAuthReplyTwo ::=
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: objectID 0,
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: key '6997257B4AF6E739'H,
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: authInfo
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: {
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: authType needLoginIdAndPasswd,
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: authLoginID "DMCCLCSUserDoNotModify"
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: },
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: encodeType winNtLocal,
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: pipe ""
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0: }
10/07/2010 08:22:18.320:TSAPI:Thread 0x62b0ba0:
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: Login: DMCCLCSUSERDONOTMODIFY App Name: Jtapi Client SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: InvokeID: 2
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: value ACSOpenStream ::=
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: {
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: streamType stReserved5,
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: serverID "AVAYA#SWLINK1#CSTA#RCIINDTELNP11",
10/07/2010 08:22:18.320:TSAPI:Thread 0x925f3ba0: loginID "DMCCLCSUserDoNotModify",
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: cryptPass '01850F0BD1CC58581204901FD1CC585812 ...'H,
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: applicationName "Jtapi Client",
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: level acsLevel1,
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: apiVer "TS2",
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: libVer "AES4.2.0 Build 279",
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: tsrvVer "4.2.2 Build 437"
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0: }
10/07/2010 08:22:18.321:TSAPI:Thread 0x925f3ba0:
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: Login: DMCCLCSUserDoNotModify App Name: Jtapi Client SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: InvokeID: 2
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: value ACSOpenStreamConfEvent ::=
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: {
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: apiVer "ST2",
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: libVer "AES4.2.0 Build 279",
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: tsrvVer "4.2.2 Build 437",
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: drvrVer "4.2.2 Build 437"
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0: }
10/07/2010 08:22:18.323:TSAPI:Thread 0x957f8ba0:
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: TSERVER Src: CLNT
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: Login: DMCCLCSUserDoNotModify App Name: Jtapi Client SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: InvokeID: 3
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: Message:
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: value CSTAMonitorDevice ::=
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: {
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: deviceID "3565",
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: monitorFilter
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: {
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: call { },
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: feature { },
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: agent { },
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: maintenance { },
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: privateFilter 0
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: }
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0: }
10/07/2010 08:24:43.905:TSAPI:Thread 0x925f3ba0:
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: TSERVER Src: DRVR
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: Login: DMCCLCSUserDoNotModify App Name: Jtapi Client SessionID: 46 Transport ID: 127.0.0.1
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: InvokeID: 3
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: Driver: AVAYA#SWLINK1#CSTA[-S]#RCIINDTELNP11
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: Message:
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: value CSTAMonitorConfEvent ::=
10/07/2010 08:24:44.034:TSAPI:Thread 0x957f8ba0: {
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: monitorCrossRefID 1,
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: monitorFilter
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: {
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: call { cfCallCleared },
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: feature { ffCallInformation, ffDoNotDisturb, ... },
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: agent { afLoggedOn, afLoggedOff, ... },
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: maintenance { mfBackInService, mfOutOfService },
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: privateFilter 0
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: }
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0: }
10/07/2010 08:24:44.035:TSAPI:Thread 0x957f8ba0:

127.0.0.1 is a loopback IP that allows the DMCC service to communicate with the TSAPI Service.

The transparent down link message parsing issues are associated with display updates that AE Services doesnt recognize... They are not impacting your problem.

The TSAPI trace shows a call control monitor being put on x3565 successfully. THat has little or nothing to do with your ServiceObserve which as I said is done entirely through DMCC. to help you I need (at a minimum) to see the dmcc-trace.log.0 trace of the SO activation attempt. It could help alot of XML tracing was enabled during that activity. THere is a FAQ that describes how to enable the tracing: in the DMCC area
https://devconnect.avaya.com/secure/faq/d_faq.jsp?f=1

How can I monitor the XML being sent and received by the AE Services Server (debug, log, trace)?

Hi John, Thanks for bearing with me. Here's a chunk of log from the last button press forward....its pressing "268" (button 6 - service observe) followed byt 3429 - to monitor. this is from button=9 forward:

<ButtonPress xmlns="http://www.ecma.ch/standards/ecma-323/csta/ed2"><device typeOfNumber="other" mediaClass="voice" bitRate="constant">3565:swlink1:10.54.236.80:0</device><button>9</button></ButtonPress>
to object=session[null] ch.ecma.csta.binding.ButtonPress@1b57c01
2010-10-07 09.31.34,850 com.avaya.mvcs.proxy.CstaRouterNode processPacket
FINE: invokeID= 210 Routing request=session[session 1E1B1186153ED48E3759C2A839C204ED-2935] ch.ecma.csta.binding.ButtonPress@1b57c01
2010-10-07 09.31.34,850 com.avaya.mvcs.station.h323.q931.H323PhysicalDevice pressButton
FINE: [10.54.236.80:3565] pressButton (button=9)
2010-10-07 09.31.34,851 com.avaya.mvcs.proxy.CstaRouterNode processPacket
FINE: Received session[null] ch.ecma.csta.binding.ButtonPressResponse@1e057c4 in response to session[session 1E1B1186153ED48E3759C2A839C204ED-2935] ch.ecma.csta.binding.ButtonPress@1b57c01
2010-10-07 09.31.34,851 com.avaya.mvcs.proxy.CstaMarshallerNode$CstaMarshallerThread <init>
FINEST: Marshaller: V42TO41_MAPPING, protocolVersion: http://www.ecma-international.org/standards/ecma-323/csta/ed3/priv2
2010-10-07 09.31.34,851 com.avaya.mvcs.proxy.CstaMarshallerNode$CstaMarshallerThread run
FINER: Packaging a session[null] ch.ecma.csta.binding.ButtonPressResponse@1e057c4
2010-10-07 09.31.34,851 com.avaya.mvcs.proxy.CstaMarshallerNode$CstaMarshallerThread run
FINEST: Marshalled session[null] ch.ecma.csta.binding.ButtonPressResponse@1e057c4 to <?xml version="1.0" encoding="UTF-8"?>
<ButtonPressResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"/>
2010-10-07 09.31.34,867 com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider hookswitchEvent
FINE: [10.54.236.80:3565] hookswitchEvent: false
2010-10-07 09.31.34,867 com.avaya.mvcs.station.h323.q931.H323PhysicalDevice offHook
FINE: [10.54.236.80:3565] offHook
2010-10-07 09.31.34,867 com.avaya.mvcs.station.h323.q931.DisplayPhoneDivider hookswitchEvent
FINE: [10.54.236.80:3565] hookswitchEvent: false
2010-10-07 09.31.34,867 com.avaya.mvcs.station.h323.q931.H323PhysicalDevice offHook
FINE: [10.54.236.80:3565] offHook

I got on the phone with Kevin for about an hour. I reviewed the mvap-trace.log.0 file someone pulled off the server and sent me. In my opinion what is happening is:

when the AE Services registers a station (x6606) Communication Manager downloads certain information into AE Services about the provisioned information (buttons) of the station that has registered. It also updates the status of the device (lamps, ringer, display). Normally this completes very quickly. For some reason (probably Communication Manager was busy doing higher priority work) at this site it is taking a long time (11 minutes in the portion of the trace I looked at).

Next your application is activating service observing. When the button push for service observing arrives, AE Services does not yet have the provisioned information for the station (button info). The AE Services server rejects the request as a result.

In the trace I got I see you tried to activate SO for about 10 minutes. In the 11th minute I think I see where Communication Manager pushed the provisioned button information into AE Services.

Your application needs to be cognizant of the reaction to the SO button push attempt. If the green lamp associated with the SO button does not come ON steady, then the system is not ready for you to proceed. The system should react with a few seconds. In this case you are getting this response

<?xml version="1.0" encoding="UTF-8"?>
<CSTAErrorCode xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><operation>invalidParameterValue</operation></CSTAErrorCode>

to your request. If you were matching up the InvokeIDs the application would know that the request the application sent in was unsuccessful and the application would not proceed.

I see elsewhere in the trace that a GetButtonInfo is done. Usually that is done to collect the provisioned button info from the system for a particular device. That way the application can dynamically react to where the SO button is provisioned. In that response button 268 is also the SO button, and at that point in the trace SO activation seems to proceed better.. .I focused on x6606 when I looked at the trace with Kevin and drew my conclusions from just that portion of the trace.

At any rate, AE Services is not yet ready to handle your SO activation, the application needs to enter into a loop trying to activate SO, when a non error response to the button push is returned, and the green lamp goes on associated with the button you pushed (even better if you wait for a GetButtonInfo to indicate that feature 85 is assigned to the button) before even trying to activate SO, then you can proceed to dial the destination party information.

I see other errors in the logs. It is not clear if they are caused by use of the dashboard, or by the application.

I also see other places where the SO button is pushed, the green lamp comes on, but no digits are dialed to activate SO for a destination.... note that when SO is successfully activated the lamp state should enter a WINK state or STEADY state... wink if there is no call active, STEADY when there is a call active.

Note finally I did not review the WHOLE log.. .I focused on x6606.

Hi John,
Still having problems. We've upgraded out AES to 4-2-3-33 as recommended by avaya and we are still having issues.

Doug

Are you waiting for the green lamp to come on on the SO button before proceeding?

Yes, according to the vendor we are. Any possibility you'd want to join a conf call?? We've been fighting this for 2 weeks

your app would know if the green lamp came on or not.. You should not be dependant on someone else's view. Kevin said you all were testing it with the dashboard as well and seeing the same issue. The dashboard will show you lamp state for the first 10 buttons on the phone tab.. The AE Services logs indicated AE Services had not been pumped up with data from Communication Manager (A DMCC application will see it as a flood of lamp updates). What are the logs showing you now? Does AE Services reject your SO button push still?

capture another dmcc trace and send it back through the AGS support engineer. I will take a look and see if anything has changed... I am interested in the dmcc-trace.log.0 file. Check the timestamps in the file to make sure you got a start to finish application initialization sequence contained therein, and tell me what extension you were working with.

I aslo forgot to mention that this same configuration is working fine at another site.

The problem is one of timing... Once you register a device, Communication Manager needs to send AE Services a bunch of information about the station before AE Services is in a state to accept requests. At this site it is 'slow'. The only way your application can handle the slowness, is to 'observe' and be patient. Observe the green lamp associated with the SO button comes on green steady when you push it as part of the activation sequence (PS if you dialed the FAC this would not be a problem at all). Further patience... if the green lamp does not come on, then wait a few seconds and push it again. I can not explain why but at the site you are at it took 11 minutes before Communication Manager pumped things up. My guess is Communication Manager was busy with other work and queued up the dump of information to AE Services until it had time to do it.

Just sent the logs off to Kevin - he'll forward to you. I still think this is a CM problem, not an AES problem

John, our vendor is receiving the following message from the AES in response to a SO request and agent ID. do you know what <lampcolor>3<lampcolor> is ?

Doug

<LampModeEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><monitorCrossRefID>130</monitorCrossRefID><device><deviceIdentifier typeOfNumber="other" mediaClass="voice" bitRate="constant">6606:swlink1:10.54.248.45:0</deviceIdentifier></device><lamp>268</lamp><lampMode>3</lampMode><lampBrightness>unspecified</lampBrightness><lampColor>3</lampColor></LampModeEvent>