« Back to Cisco JTAPI Questions

RE: TransferEnd event not delivered

Combination View Flat View Tree View
Threads [ Previous | Next ]
TransferEnd event not delivered
Answer
4/18/12 11:28 AM
Hi!
 
My application have behaviours that depends on TransferStart and TransferEnd events. Application thinks that if it received a TransferStart event than it will receive TransferEnd event later. Is this right behaviour? 
 
I am using CCM 7.1.5
 
There is an event log for consult transfer of external call (exactly this case provide TransferStart without TransferEnd), 122 and 110 are internal numbers:
 

CallActiveEvImpl (CID=16840224), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnCreatedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnInProgressEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlConnOfferedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnCreatedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnConnectedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnAlertingEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlConnAlertingEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
TermConnCreatedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_NORMAL
TermConnRingingEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlTermConnRingingEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnConnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
TermConnActiveEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlTermConnTalkingEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CiscoTermConnRecordingStartedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_NORMAL
CiscoTermConnRecordingTargetInfoEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_NORMAL
CiscoTermConnSelectChangedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_NORMAL
CallCtlTermConnHeldEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
CiscoTermConnRecordingEndEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CiscoTransferStartEvImpl (CID=16840224), CiscoCause = CAUSE_CTIPRECEDENCEOUTOFBANDWIDTH, FeatureReason = REASON_TRANSFER
CiscoTransferStartEvImpl (CID=16840224), CiscoCause = CAUSE_CTIPRECEDENCEOUTOFBANDWIDTH, FeatureReason = REASON_TRANSFER
CiscoCallChangedEvImpl (CID=16840227, OrigCID=16840227, SurvCID=16840224), CiscoCause = UNKNOWN (212), FeatureReason = REASON_TRANSFER
CallActiveEvImpl (CID=16840224), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
ConnCreatedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CiscoCallChangedEvImpl (CID=16840227, OrigCID=16840227, SurvCID=16840224), CiscoCause = UNKNOWN (212), FeatureReason = REASON_TRANSFER
ConnConnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
TermConnCreatedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
ConnCreatedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
TermConnActiveEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlTermConnHeldEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
ConnAlertingEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
ConnCreatedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlConnAlertingEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
ConnConnectedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
TermConnCreatedEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_TRANSFER
ConnCreatedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
TermConnRingingEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
ConnAlertingEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlConnAlertingEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlTermConnRingingEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
TermConnCreatedEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
TermConnRingingEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
CallCtlTermConnRingingEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_NORMAL
ConnCreatedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
ConnConnectedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
TermConnDroppedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
ConnCreatedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
CallCtlTermConnDroppedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
ConnDisconnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
CallCtlConnDisconnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
ConnConnectedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
TermConnDroppedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
CallCtlTermConnDroppedEvImpl (CID=16840224, CRID=20284004, DN=122, TN=SEP64168DBB00F5, CHash=8512673, TCHash=5688723), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
ConnDisconnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
CallCtlConnDisconnectedEvImpl (CID=16840224, CRID=20284004, DN=122, CHash=8512673), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_TRANSFER
ConnConnectedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlConnEstablishedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
TermConnActiveEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
CallCtlTermConnTalkingEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NOERROR, FeatureReason = REASON_NORMAL
ConnDisconnectedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
CallCtlConnDisconnectedEvImpl (CID=16840224, CRID=0, DN=3833630909, CHash=22651287), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
ConnDisconnectedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
CallCtlConnDisconnectedEvImpl (CID=16840224, CRID=0, DN=Unknown, CHash=25036373), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
TermConnDroppedEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
CallCtlTermConnDroppedEvImpl (CID=16840224, CRID=20284014, DN=110, TN=SEP0016E6D334D7, CHash=23617280, TCHash=5614780), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
ConnDisconnectedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
CallCtlConnDisconnectedEvImpl (CID=16840224, CRID=20284014, DN=110, CHash=23617280), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
CallInvalidEvImpl (CID=16840224), CiscoCause = CAUSE_NORMALCALLCLEARING, FeatureReason = REASON_NORMAL
Flag

RE: TransferEnd event not delivered
Answer
4/20/12 9:06 PM as a reply to Sergei Gorbunov.
Hmm, normally I would expect transfer start to have a transfer end...can you provide the JTAPI logs attached here?  Please enable detailed-level and all-types.
Flag

RE: TransferEnd event not delivered
Answer
4/23/12 6:14 AM as a reply to David Staudt.
There is an JTAPI log file. It contains a TransferEnded events, but my application did not received them. You can see them at "апр 23 11:34:11.995" in JTAPI log. But in that time my application received only this events:
 
 
2012-04-23 11:34:11,870 CiscoTransferStartEvImpl (CID=16841233), CiscoCause = CAUSE_CTIPRECEDENCEOUTOFBANDWIDTH, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:11,870 CiscoTransferStartEvImpl (CID=16841233), CiscoCause = CAUSE_CTIPRECEDENCEOUTOFBANDWIDTH, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:11,979 CiscoRTPInputStoppedEvImpl (TN=SEP64168DBB00F5), Cause = CAUSE_INVALIDIECONTENTS
2012-04-23 11:34:11,979 CiscoRTPOutputStoppedEvImpl (TN=SEP64168DBB00F5), Cause = CAUSE_INVALIDIECONTENTS
2012-04-23 11:34:11,979 CiscoRTPInputStoppedEvImpl (TN=SEP0016E6D334D7), Cause = CAUSE_INVALIDIECONTENTS
2012-04-23 11:34:11,979 CiscoRTPOutputStoppedEvImpl (TN=SEP0016E6D334D7), Cause = CAUSE_INVALIDIECONTENTS
2012-04-23 11:34:11,995 CiscoCallChangedEvImpl (CID=16841236, OrigCID=16841236, SurvCID=16841233), CiscoCause = UNKNOWN (212), FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 ConnCreatedEvImpl (CID=16841233, CRID=22224800, DN=110, CHash=7710532), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 ConnConnectedEvImpl (CID=16841233, CRID=22224800, DN=110, CHash=7710532), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 CiscoCallChangedEvImpl (CID=16841236, OrigCID=16841236, SurvCID=16841233), CiscoCause = UNKNOWN (212), FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 CallCtlConnEstablishedEvImpl (CID=16841233, CRID=22224800, DN=110, CHash=7710532), CiscoCause = CAUSE_NORMALUNSPECIFIED, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 TermConnCreatedEvImpl (CID=16841233, CRID=22224800, DN=110, TN=SEP0016E6D334D7, CHash=7710532, TCHash=18602599), CiscoCause = CAUSE_INVALIDIECONTENTS, FeatureReason = REASON_TRANSFER
2012-04-23 11:34:12,011 TermConnActiveEvImpl (CID=16841233, CRID=22224800, DN=110, TN=SEP0016E6D334D7, CHash=7710532, TCHash=18602599), CiscoCause =
Attachments:
Flag

RE: TransferEnd event not delivered
Answer
5/7/12 10:52 PM as a reply to Sergei Gorbunov.
The log seems to confirm that the transfer ended event was delivered to these observers:
 
1630: àïð 23 11:34:12.011 NOVT %JTAPI-CTIIMPL-7-UNKemoticonP1-192.168.202.8) EventThread handling event com.cisco.cti.protocol.LineTransferEndedEvent[68]
1631: àïð 23 11:34:12.011 NOVT %JTAPI-CTIIMPL-7-UNK:SEP0016E6D334D7:110:emoticon1,288) Recieved Last TxEndEv for this line; sending to JTAPI layer
1632: àïð 23 11:34:12.011 NOVT %JTAPI-CTI-7-UNKemoticonP1-axluser){Line:SEP0016E6D334D7:110:emoticon1,288)} LineTransferEndedEvent [ ID= 16777218 success= true RIU= false Idle= null null]
1633: àïð 23 11:34:12.011 NOVT %JTAPI-JTAPIIMPL-7-UNK:Line :SEP0016E6D334D7:110:emoticon1,288)AddressCallObserver.transferEndedEvent()
1634: àïð 23 11:34:12.011 NOVT %JTAPI-JTAPIIMPL-7-UNK:TransferManager.transferEndedEvent:  TransactioID=16777218 transferEnded=true txStartEndCount=1 numOfConfStChgEvForPtyAdd=0
1635: àïð 23 11:34:12.011 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1636: àïð 23 11:34:12.011 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[16]
1637: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1638: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1639: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1640: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[4]
1641: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1642: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1643: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1644: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[1]
1645: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1646: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1647: àïð 23 11:34:12.026 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1648: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[2]
1649: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1650: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1651: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1652: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[1]
1653: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1654: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1655: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1656: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[5]
1657: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1658: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1659: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1660: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[1]
1661: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1662: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1663: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1664: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2): delivering JCES[4]
1665: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents()
1666: àïð 23 11:34:12.042 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]delivering to callChangedEvent
1667: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@17361e2]ObserverProxy.deliverEvents() completed
1668: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1669: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[4]
1670: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1671: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1672: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1673: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[1]
1674: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1675: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1676: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1677: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[1]
1678: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1679: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1680: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1681: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[2]
1682: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1683: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1684: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
1685: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNKemoticonbserverThread(com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7): delivering JCES[4]
1686: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents()
1687: àïð 23 11:34:12.057 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]delivering to callChangedEvent
1688: àïð 23 11:34:12.073 NOVT %JTAPI-JTAPIIMPL-7-UNK:[com.bcsit.phoneup.telephony.Observers.PhoneUPCallObserver@1c5cd7]ObserverProxy.deliverEvents() completed
 
The event was signaled it looks like at the same time or later than your final event above..?  Is it possible your logic stops listening for the event based on one of the previous events..some sort of race conditions?  Note many event orders are not guaranteed and can change, sometimes in rare situations...
Flag

RE: TransferEnd event not delivered
Answer
5/11/12 5:05 AM as a reply to David Staudt.
I've thought about this scenario, but it is not possible. I am adding address call observers when my app starting and remove them when it shutdown. As you can see in logs i am receiving events before and after TransferEnd. My app event logging occurs before event processing, event processing occurs in try/catch blocks and all errors are logged. I'll try to make a test app to reproduce this bug.
Flag

RE: TransferEnd event not delivered
Answer
5/11/12 5:05 AM as a reply to David Staudt.
I've thought about this scenario, but it is not possible. I am adding address call observers when my app starting and remove them when it shutdown. As you can see in logs i am receiving events before and after TransferEnd. My app event logging occurs before event processing, event processing occurs in try/catch blocks and all errors are logged. I'll try to make a test app to reproduce this bug.
Flag

RE: TransferEnd event not delivered
Answer
5/11/12 5:19 AM as a reply to David Staudt.
David, there is a log file from JTAPITestTool. There is also no TransferEnd event! I think this is surely bug.
Attachments:
Flag

RE: TransferEnd event not delivered
Answer
7/18/12 10:46 AM as a reply to Sergei Gorbunov.
There is a scenario that reproduce a problem with 100% probability (reproduced on CUCM 7.1.5 and 8.6.2):

1. Address A calls to Hunt Pilot address B.
2. Some address C (from hunt line group) answers to call.
3. Address C makes transfer to address D.
4. JTAPI receive TransferStart event but not receive TransferEnd.
Flag

RE: TransferEnd event not delivered
Answer
7/18/12 4:44 PM as a reply to Sergei Gorbunov.
Having the Hunt List part of the scenario may explain the problem: support for monitoring phones which are members of a Hunt List is present in 8.0(1) and forward, and requires enabling a specific flag (setHuntListFeatureEnabled (boolean) on CiscoJtapiProperties, also can be set via jtapi.ini.)  If either the UCM version doesn't support Hunt List target observation or the feature is not enabled, then problems with the call model/events are a known limitation.  See the 'Hunt List' section in the JTAPI Developer Guide for details.
 
Note that enabling the feature will cause a novel call model to occur in Hunt monitoring situations, which may require some changes in your app.
Flag

RE: TransferEnd event not delivered
Answer
7/19/12 7:01 AM as a reply to David Staudt.
Yes, hunt list is required part for this issue. But I receive TransferStart event for terminal adresses (A, C or D) and expect to receive a TransferEnd event. TransferEnd is not delevered to my app. This behaviuor tested with Cisco JTAPI Test Tools.
Flag

RE: TransferEnd event not delivered
Answer
7/19/12 5:39 PM as a reply to Sergei Gorbunov.
Note, you must be running UCM UCM 8.0(1) or higher and enable Hunt List support feature (most easily via jtprefs/jtapi.ini) in order to expect complete call model and events.  Without this configuration enabled, missing events and inconsistent call model are an expected limitation.
 
If after enabling the option you still see missing events or other call model problems please capture the relevant logs - I would recommend opening a CDN Developer Support case at that point.
 
http://developer.cisco.com/web/devservices/alldevs#case
Flag